{"level":"warn","ts":"2026-04-30T05:55:14.788004Z","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-30T05:55:14.788101Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.103:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.103:2380","--initial-cluster=instance=https://199.19.213.103:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.103:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.103: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-30T05:55:14.788188Z","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-30T05:55:14.788202Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-04-30T05:55:14.788222Z","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-30T05:55:14.78883Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"]} {"level":"info","ts":"2026-04-30T05:55:14.78891Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.103: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-30T05:55:14.793472Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.332941ms"} {"level":"info","ts":"2026-04-30T05:55:14.799891Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d82047944f922143","cluster-id":"223a09c0da413f53"} {"level":"info","ts":"2026-04-30T05:55:14.799978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=()"} {"level":"info","ts":"2026-04-30T05:55:14.800019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 0"} {"level":"info","ts":"2026-04-30T05:55:14.800036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d82047944f922143 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-30T05:55:14.800046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 1"} {"level":"info","ts":"2026-04-30T05:55:14.8001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"warn","ts":"2026-04-30T05:55:14.809448Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-30T05:55:14.810978Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-30T05:55:14.812599Z","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-30T05:55:14.814903Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d82047944f922143","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-30T05:55:14.815973Z","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-30T05:55:14.816108Z","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-30T05:55:14.816125Z","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-30T05:55:14.815459Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d82047944f922143","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-30T05:55:14.816624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"info","ts":"2026-04-30T05:55:14.816789Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","added-peer-id":"d82047944f922143","added-peer-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-04-30T05:55:14.818785Z","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-30T05:55:14.818988Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d82047944f922143","initial-advertise-peer-urls":["https://199.19.213.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-30T05:55:14.819013Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-30T05:55:14.819104Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-04-30T05:55:14.819118Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-04-30T05:55:15.101093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 is starting a new election at term 1"} {"level":"info","ts":"2026-04-30T05:55:15.101215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-30T05:55:15.101256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgPreVoteResp from d82047944f922143 at term 1"} {"level":"info","ts":"2026-04-30T05:55:15.101274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became candidate at term 2"} {"level":"info","ts":"2026-04-30T05:55:15.101286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgVoteResp from d82047944f922143 at term 2"} {"level":"info","ts":"2026-04-30T05:55:15.101315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became leader at term 2"} {"level":"info","ts":"2026-04-30T05:55:15.101331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d82047944f922143 elected leader d82047944f922143 at term 2"} {"level":"info","ts":"2026-04-30T05:55:15.102639Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d82047944f922143","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.103:2379]}","request-path":"/0/members/d82047944f922143/attributes","cluster-id":"223a09c0da413f53","publish-timeout":"7s"} {"level":"info","ts":"2026-04-30T05:55:15.102693Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T05:55:15.102837Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-30T05:55:15.102778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-30T05:55:15.105744Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-30T05:55:15.105797Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-30T05:55:15.106165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.103:2379"} {"level":"info","ts":"2026-04-30T05:55:15.108206Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-30T05:55:15.113519Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T05:55:15.113603Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T05:55:15.113632Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T05:55:16.759546Z","caller":"traceutil/trace.go:171","msg":"trace[276112986] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"161.751129ms","start":"2026-04-30T05:55:16.597739Z","end":"2026-04-30T05:55:16.759491Z","steps":["trace[276112986] 'process raft request' (duration: 154.088006ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.759638Z","caller":"traceutil/trace.go:171","msg":"trace[1504180316] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"161.80371ms","start":"2026-04-30T05:55:16.597815Z","end":"2026-04-30T05:55:16.759619Z","steps":["trace[1504180316] 'process raft request' (duration: 161.631629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:16.759768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.900351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-30T05:55:16.759858Z","caller":"traceutil/trace.go:171","msg":"trace[272641253] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"162.035841ms","start":"2026-04-30T05:55:16.597806Z","end":"2026-04-30T05:55:16.759842Z","steps":["trace[272641253] 'agreement among raft nodes before linearized reading' (duration: 161.85432ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.759597Z","caller":"traceutil/trace.go:171","msg":"trace[606140755] linearizableReadLoop","detail":"{readStateIndex:10; appliedIndex:6; }","duration":"161.74199ms","start":"2026-04-30T05:55:16.59783Z","end":"2026-04-30T05:55:16.759572Z","steps":["trace[606140755] 'read index received' (duration: 3.302523ms)","trace[606140755] 'applied index is now lower than readState.Index' (duration: 158.438237ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:16.760074Z","caller":"traceutil/trace.go:171","msg":"trace[1074867420] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"162.202804ms","start":"2026-04-30T05:55:16.597859Z","end":"2026-04-30T05:55:16.760062Z","steps":["trace[1074867420] 'process raft request' (duration: 161.655909ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.760147Z","caller":"traceutil/trace.go:171","msg":"trace[1333719137] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"162.228143ms","start":"2026-04-30T05:55:16.59791Z","end":"2026-04-30T05:55:16.760138Z","steps":["trace[1333719137] 'process raft request' (duration: 161.631048ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.760303Z","caller":"traceutil/trace.go:171","msg":"trace[1020428221] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"162.485015ms","start":"2026-04-30T05:55:16.597811Z","end":"2026-04-30T05:55:16.760296Z","steps":["trace[1020428221] 'process raft request' (duration: 161.603029ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.768449Z","caller":"traceutil/trace.go:171","msg":"trace[1087942245] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"160.07764ms","start":"2026-04-30T05:55:16.608353Z","end":"2026-04-30T05:55:16.76843Z","steps":["trace[1087942245] 'process raft request' (duration: 159.972648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:16.768442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.005287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-30T05:55:16.768514Z","caller":"traceutil/trace.go:171","msg":"trace[1322122966] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"164.105418ms","start":"2026-04-30T05:55:16.604393Z","end":"2026-04-30T05:55:16.768498Z","steps":["trace[1322122966] 'agreement among raft nodes before linearized reading' (duration: 163.981267ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.768576Z","caller":"traceutil/trace.go:171","msg":"trace[1352509164] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"164.630291ms","start":"2026-04-30T05:55:16.603934Z","end":"2026-04-30T05:55:16.768565Z","steps":["trace[1352509164] 'process raft request' (duration: 164.294198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:16.768552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.539486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-30T05:55:16.768709Z","caller":"traceutil/trace.go:171","msg":"trace[526330610] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"119.671237ms","start":"2026-04-30T05:55:16.648973Z","end":"2026-04-30T05:55:16.768644Z","steps":["trace[526330610] 'agreement among raft nodes before linearized reading' (duration: 119.542596ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.768791Z","caller":"traceutil/trace.go:171","msg":"trace[1258503273] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"152.100314ms","start":"2026-04-30T05:55:16.616363Z","end":"2026-04-30T05:55:16.768463Z","steps":["trace[1258503273] 'process raft request' (duration: 151.989962ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.959584Z","caller":"traceutil/trace.go:171","msg":"trace[308586463] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"129.389514ms","start":"2026-04-30T05:55:16.830165Z","end":"2026-04-30T05:55:16.959554Z","steps":["trace[308586463] 'process raft request' (duration: 123.719615ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.96104Z","caller":"traceutil/trace.go:171","msg":"trace[1619257088] linearizableReadLoop","detail":"{readStateIndex:36; appliedIndex:33; }","duration":"129.332084ms","start":"2026-04-30T05:55:16.83168Z","end":"2026-04-30T05:55:16.961012Z","steps":["trace[1619257088] 'read index received' (duration: 122.253475ms)","trace[1619257088] 'applied index is now lower than readState.Index' (duration: 7.077599ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:16.961141Z","caller":"traceutil/trace.go:171","msg":"trace[1856471865] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"130.383962ms","start":"2026-04-30T05:55:16.830733Z","end":"2026-04-30T05:55:16.961117Z","steps":["trace[1856471865] 'process raft request' (duration: 129.986109ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.961344Z","caller":"traceutil/trace.go:171","msg":"trace[27430585] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"129.078882ms","start":"2026-04-30T05:55:16.832251Z","end":"2026-04-30T05:55:16.961329Z","steps":["trace[27430585] 'process raft request' (duration: 128.67891ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.961387Z","caller":"traceutil/trace.go:171","msg":"trace[1499106038] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"128.706119ms","start":"2026-04-30T05:55:16.832661Z","end":"2026-04-30T05:55:16.961367Z","steps":["trace[1499106038] 'process raft request' (duration: 128.291697ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.961417Z","caller":"traceutil/trace.go:171","msg":"trace[1298365050] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"130.016398ms","start":"2026-04-30T05:55:16.83139Z","end":"2026-04-30T05:55:16.961406Z","steps":["trace[1298365050] 'process raft request' (duration: 129.471804ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.96145Z","caller":"traceutil/trace.go:171","msg":"trace[241851639] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"129.233383ms","start":"2026-04-30T05:55:16.832207Z","end":"2026-04-30T05:55:16.961441Z","steps":["trace[241851639] 'process raft request' (duration: 128.691639ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:16.961522Z","caller":"traceutil/trace.go:171","msg":"trace[1676200168] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"128.040024ms","start":"2026-04-30T05:55:16.833474Z","end":"2026-04-30T05:55:16.961514Z","steps":["trace[1676200168] 'process raft request' (duration: 127.505101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:16.96156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.853707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-30T05:55:16.961611Z","caller":"traceutil/trace.go:171","msg":"trace[1087349263] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:36; }","duration":"129.955849ms","start":"2026-04-30T05:55:16.831639Z","end":"2026-04-30T05:55:16.961595Z","steps":["trace[1087349263] 'agreement among raft nodes before linearized reading' (duration: 129.481825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:17.249141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.435073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-30T05:55:17.249396Z","caller":"traceutil/trace.go:171","msg":"trace[1369501018] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"199.786091ms","start":"2026-04-30T05:55:17.04957Z","end":"2026-04-30T05:55:17.249356Z","steps":["trace[1369501018] 'process raft request' (duration: 199.713901ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:17.249385Z","caller":"traceutil/trace.go:171","msg":"trace[305816020] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"284.066558ms","start":"2026-04-30T05:55:16.965263Z","end":"2026-04-30T05:55:17.249329Z","steps":["trace[305816020] 'process raft request' (duration: 164.767338ms)","trace[305816020] 'compare' (duration: 118.299553ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:17.596566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.026307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-30T05:55:17.596828Z","caller":"traceutil/trace.go:171","msg":"trace[1966706939] linearizableReadLoop","detail":"{readStateIndex:68; appliedIndex:66; }","duration":"135.065321ms","start":"2026-04-30T05:55:17.461741Z","end":"2026-04-30T05:55:17.596806Z","steps":["trace[1966706939] 'read index received' (duration: 21.773591ms)","trace[1966706939] 'applied index is now lower than readState.Index' (duration: 113.29055ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:17.596985Z","caller":"traceutil/trace.go:171","msg":"trace[1720161847] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"137.477497ms","start":"2026-04-30T05:55:17.459478Z","end":"2026-04-30T05:55:17.596955Z","steps":["trace[1720161847] 'process raft request' (duration: 137.263416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:17.597086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.653597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-30T05:55:17.597128Z","caller":"traceutil/trace.go:171","msg":"trace[736532481] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:64; }","duration":"101.706678ms","start":"2026-04-30T05:55:17.495408Z","end":"2026-04-30T05:55:17.597115Z","steps":["trace[736532481] 'agreement among raft nodes before linearized reading' (duration: 101.659938ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:17.597224Z","caller":"traceutil/trace.go:171","msg":"trace[851377049] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"152.731664ms","start":"2026-04-30T05:55:17.444471Z","end":"2026-04-30T05:55:17.597203Z","steps":["trace[851377049] 'process raft request' (duration: 38.978962ms)","trace[851377049] 'compare' (duration: 112.823906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:17.597037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.287202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-30T05:55:17.597289Z","caller":"traceutil/trace.go:171","msg":"trace[1842907872] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:64; }","duration":"135.573444ms","start":"2026-04-30T05:55:17.461697Z","end":"2026-04-30T05:55:17.597271Z","steps":["trace[1842907872] 'agreement among raft nodes before linearized reading' (duration: 135.246601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:17.597452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.318146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-30T05:55:17.597502Z","caller":"traceutil/trace.go:171","msg":"trace[625429733] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:64; }","duration":"101.411287ms","start":"2026-04-30T05:55:17.496079Z","end":"2026-04-30T05:55:17.59749Z","steps":["trace[625429733] 'agreement among raft nodes before linearized reading' (duration: 101.338196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:17.874236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.322478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-30T05:55:17.874435Z","caller":"traceutil/trace.go:171","msg":"trace[1119405269] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"196.533277ms","start":"2026-04-30T05:55:17.677881Z","end":"2026-04-30T05:55:17.874414Z","steps":["trace[1119405269] 'process raft request' (duration: 196.477807ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:17.874672Z","caller":"traceutil/trace.go:171","msg":"trace[103004283] linearizableReadLoop","detail":"{readStateIndex:73; appliedIndex:72; }","duration":"197.212893ms","start":"2026-04-30T05:55:17.677403Z","end":"2026-04-30T05:55:17.874616Z","steps":["trace[103004283] 'read index received' (duration: 50.143209ms)","trace[103004283] 'applied index is now lower than readState.Index' (duration: 147.064284ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:17.874692Z","caller":"traceutil/trace.go:171","msg":"trace[1660641246] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"197.613896ms","start":"2026-04-30T05:55:17.677027Z","end":"2026-04-30T05:55:17.874641Z","steps":["trace[1660641246] 'process raft request' (duration: 50.567812ms)","trace[1660641246] 'compare' (duration: 146.193537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:17.87491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.496494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-30T05:55:17.874963Z","caller":"traceutil/trace.go:171","msg":"trace[93592219] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:70; }","duration":"197.571115ms","start":"2026-04-30T05:55:17.677377Z","end":"2026-04-30T05:55:17.874949Z","steps":["trace[93592219] 'agreement among raft nodes before linearized reading' (duration: 197.409544ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:18.029866Z","caller":"traceutil/trace.go:171","msg":"trace[677180311] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"106.311269ms","start":"2026-04-30T05:55:17.923522Z","end":"2026-04-30T05:55:18.029833Z","steps":["trace[677180311] 'process raft request' (duration: 88.982679ms)","trace[677180311] 'compare' (duration: 16.901388ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:19.588101Z","caller":"traceutil/trace.go:171","msg":"trace[1245346368] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:245; }","duration":"157.080804ms","start":"2026-04-30T05:55:19.430995Z","end":"2026-04-30T05:55:19.588076Z","steps":["trace[1245346368] 'read index received' (duration: 47.054785ms)","trace[1245346368] 'applied index is now lower than readState.Index' (duration: 110.024959ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:19.588168Z","caller":"traceutil/trace.go:171","msg":"trace[1031945762] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"179.69808ms","start":"2026-04-30T05:55:19.408427Z","end":"2026-04-30T05:55:19.588125Z","steps":["trace[1031945762] 'process raft request' (duration: 134.490738ms)","trace[1031945762] 'compare' (duration: 45.040251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:19.588311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.392549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.103\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T05:55:19.588337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.323966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-30T05:55:19.588395Z","caller":"traceutil/trace.go:171","msg":"trace[72947307] range","detail":"{range_begin:/registry/masterleases/199.19.213.103; range_end:; response_count:0; response_revision:242; }","duration":"108.52699ms","start":"2026-04-30T05:55:19.479855Z","end":"2026-04-30T05:55:19.588382Z","steps":["trace[72947307] 'agreement among raft nodes before linearized reading' (duration: 108.384489ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:19.588416Z","caller":"traceutil/trace.go:171","msg":"trace[1922928636] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:242; }","duration":"157.457137ms","start":"2026-04-30T05:55:19.43094Z","end":"2026-04-30T05:55:19.588397Z","steps":["trace[1922928636] 'agreement among raft nodes before linearized reading' (duration: 157.257864ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:20.325748Z","caller":"traceutil/trace.go:171","msg":"trace[234249536] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"164.62327ms","start":"2026-04-30T05:55:20.161101Z","end":"2026-04-30T05:55:20.325724Z","steps":["trace[234249536] 'read index received' (duration: 141.25123ms)","trace[234249536] 'applied index is now lower than readState.Index' (duration: 23.37107ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:20.32586Z","caller":"traceutil/trace.go:171","msg":"trace[1097858107] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"182.362113ms","start":"2026-04-30T05:55:20.14346Z","end":"2026-04-30T05:55:20.325823Z","steps":["trace[1097858107] 'process raft request' (duration: 158.876552ms)","trace[1097858107] 'compare' (duration: 23.2719ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:20.325943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.823781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:55:20.325984Z","caller":"traceutil/trace.go:171","msg":"trace[1966827695] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:255; }","duration":"164.927323ms","start":"2026-04-30T05:55:20.16104Z","end":"2026-04-30T05:55:20.325967Z","steps":["trace[1966827695] 'agreement among raft nodes before linearized reading' (duration: 164.831802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:20.327662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.638555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-04-30T05:55:20.327731Z","caller":"traceutil/trace.go:171","msg":"trace[1582380225] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:256; }","duration":"147.915596ms","start":"2026-04-30T05:55:20.179804Z","end":"2026-04-30T05:55:20.327719Z","steps":["trace[1582380225] 'agreement among raft nodes before linearized reading' (duration: 147.769746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:20.721995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.681932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-30T05:55:20.722301Z","caller":"traceutil/trace.go:171","msg":"trace[135629186] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"341.792296ms","start":"2026-04-30T05:55:20.380485Z","end":"2026-04-30T05:55:20.722277Z","steps":["trace[135629186] 'process raft request' (duration: 132.749842ms)","trace[135629186] 'compare' (duration: 208.540961ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:20.722491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:20.380472Z","time spent":"341.983748ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T05:55:20.72587Z","caller":"traceutil/trace.go:171","msg":"trace[1015552139] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"343.891751ms","start":"2026-04-30T05:55:20.381954Z","end":"2026-04-30T05:55:20.725846Z","steps":["trace[1015552139] 'process raft request' (duration: 343.845001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:20.726011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:20.381942Z","time spent":"344.008602ms","remote":"127.0.0.1:53230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T05:55:20.726185Z","caller":"traceutil/trace.go:171","msg":"trace[180768166] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"344.731576ms","start":"2026-04-30T05:55:20.381432Z","end":"2026-04-30T05:55:20.726163Z","steps":["trace[180768166] 'process raft request' (duration: 344.254153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:20.727091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:20.381413Z","time spent":"344.848088ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T05:55:21.118466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.922703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-30T05:55:21.118704Z","caller":"traceutil/trace.go:171","msg":"trace[876341643] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:270; }","duration":"195.968245ms","start":"2026-04-30T05:55:20.922716Z","end":"2026-04-30T05:55:21.118684Z","steps":["trace[876341643] 'read index received' (duration: 175.843226ms)","trace[876341643] 'applied index is now lower than readState.Index' (duration: 20.123969ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:21.118837Z","caller":"traceutil/trace.go:171","msg":"trace[1358182253] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"338.540406ms","start":"2026-04-30T05:55:20.780285Z","end":"2026-04-30T05:55:21.118825Z","steps":["trace[1358182253] 'process raft request' (duration: 338.351785ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:21.118873Z","caller":"traceutil/trace.go:171","msg":"trace[325082041] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"382.939111ms","start":"2026-04-30T05:55:20.735857Z","end":"2026-04-30T05:55:21.118796Z","steps":["trace[325082041] 'process raft request' (duration: 96.618954ms)","trace[325082041] 'compare' (duration: 285.805002ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:21.118951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:20.780258Z","time spent":"338.663786ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T05:55:21.119042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:20.735845Z","time spent":"383.130542ms","remote":"127.0.0.1:53428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":468,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T05:55:21.119237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.840629ms","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-30T05:55:21.11934Z","caller":"traceutil/trace.go:171","msg":"trace[1885083282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:266; }","duration":"136.99093ms","start":"2026-04-30T05:55:20.982332Z","end":"2026-04-30T05:55:21.119323Z","steps":["trace[1885083282] 'agreement among raft nodes before linearized reading' (duration: 136.739298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:21.119605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.88423ms","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-30T05:55:21.119653Z","caller":"traceutil/trace.go:171","msg":"trace[2021983936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:266; }","duration":"196.982532ms","start":"2026-04-30T05:55:20.922658Z","end":"2026-04-30T05:55:21.119641Z","steps":["trace[2021983936] 'agreement among raft nodes before linearized reading' (duration: 196.877581ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:21.321865Z","caller":"traceutil/trace.go:171","msg":"trace[529079872] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"103.65975ms","start":"2026-04-30T05:55:21.218176Z","end":"2026-04-30T05:55:21.321836Z","steps":["trace[529079872] 'process raft request' (duration: 12.956959ms)","trace[529079872] 'compare' (duration: 90.56572ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:23.196719Z","caller":"traceutil/trace.go:171","msg":"trace[129894329] transaction","detail":"{read_only:false; number_of_response:0; response_revision:324; }","duration":"116.18053ms","start":"2026-04-30T05:55:23.080508Z","end":"2026-04-30T05:55:23.196688Z","steps":["trace[129894329] 'process raft request' (duration: 115.991249ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:23.196723Z","caller":"traceutil/trace.go:171","msg":"trace[1129459563] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:335; }","duration":"115.600416ms","start":"2026-04-30T05:55:23.081094Z","end":"2026-04-30T05:55:23.196694Z","steps":["trace[1129459563] 'read index received' (duration: 92.70513ms)","trace[1129459563] 'applied index is now lower than readState.Index' (duration: 22.893716ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:23.196986Z","caller":"traceutil/trace.go:171","msg":"trace[602231794] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"109.791916ms","start":"2026-04-30T05:55:23.087181Z","end":"2026-04-30T05:55:23.196973Z","steps":["trace[602231794] 'process raft request' (duration: 109.399804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:23.197167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.040189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-30T05:55:23.197235Z","caller":"traceutil/trace.go:171","msg":"trace[1613007431] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:325; }","duration":"116.134039ms","start":"2026-04-30T05:55:23.081083Z","end":"2026-04-30T05:55:23.197217Z","steps":["trace[1613007431] 'agreement among raft nodes before linearized reading' (duration: 116.013049ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:23.395155Z","caller":"traceutil/trace.go:171","msg":"trace[1648255314] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:343; }","duration":"114.934152ms","start":"2026-04-30T05:55:23.280198Z","end":"2026-04-30T05:55:23.395132Z","steps":["trace[1648255314] 'read index received' (duration: 111.8194ms)","trace[1648255314] 'applied index is now lower than readState.Index' (duration: 3.113602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:23.39526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.036743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:55:23.395323Z","caller":"traceutil/trace.go:171","msg":"trace[1076389810] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:331; }","duration":"115.136003ms","start":"2026-04-30T05:55:23.280174Z","end":"2026-04-30T05:55:23.39531Z","steps":["trace[1076389810] 'agreement among raft nodes before linearized reading' (duration: 115.041233ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:23.395433Z","caller":"traceutil/trace.go:171","msg":"trace[803633789] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"152.972801ms","start":"2026-04-30T05:55:23.242272Z","end":"2026-04-30T05:55:23.395244Z","steps":["trace[803633789] 'process raft request' (duration: 149.676078ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:24.468613Z","caller":"traceutil/trace.go:171","msg":"trace[105074458] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"170.655366ms","start":"2026-04-30T05:55:24.297934Z","end":"2026-04-30T05:55:24.468589Z","steps":["trace[105074458] 'process raft request' (duration: 156.718462ms)","trace[105074458] 'compare' (duration: 13.848544ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:24.468733Z","caller":"traceutil/trace.go:171","msg":"trace[2090861099] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"137.567552ms","start":"2026-04-30T05:55:24.331127Z","end":"2026-04-30T05:55:24.468695Z","steps":["trace[2090861099] 'read index received' (duration: 123.525477ms)","trace[2090861099] 'applied index is now lower than readState.Index' (duration: 14.038325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:24.468917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.776693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-30T05:55:24.46897Z","caller":"traceutil/trace.go:171","msg":"trace[960557148] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:342; }","duration":"137.877283ms","start":"2026-04-30T05:55:24.331079Z","end":"2026-04-30T05:55:24.468956Z","steps":["trace[960557148] 'agreement among raft nodes before linearized reading' (duration: 137.690622ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:25.055418Z","caller":"traceutil/trace.go:171","msg":"trace[1174948654] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"257.370111ms","start":"2026-04-30T05:55:24.798014Z","end":"2026-04-30T05:55:25.055384Z","steps":["trace[1174948654] 'process raft request' (duration: 257.042649ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:25.055582Z","caller":"traceutil/trace.go:171","msg":"trace[2143406329] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:359; }","duration":"252.248716ms","start":"2026-04-30T05:55:24.803008Z","end":"2026-04-30T05:55:25.055257Z","steps":["trace[2143406329] 'read index received' (duration: 78.603702ms)","trace[2143406329] 'applied index is now lower than readState.Index' (duration: 173.644074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:25.055612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.578658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-30T05:55:25.055845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.31327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-30T05:55:25.055899Z","caller":"traceutil/trace.go:171","msg":"trace[36049641] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:348; }","duration":"124.450621ms","start":"2026-04-30T05:55:24.931433Z","end":"2026-04-30T05:55:25.055884Z","steps":["trace[36049641] 'agreement among raft nodes before linearized reading' (duration: 124.32021ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:25.055997Z","caller":"traceutil/trace.go:171","msg":"trace[1227918368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"252.845841ms","start":"2026-04-30T05:55:24.802974Z","end":"2026-04-30T05:55:25.05582Z","steps":["trace[1227918368] 'agreement among raft nodes before linearized reading' (duration: 252.547008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:25.056235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.447246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-30T05:55:25.056257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.391484ms","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-30T05:55:25.056455Z","caller":"traceutil/trace.go:171","msg":"trace[467145928] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:348; }","duration":"225.632836ms","start":"2026-04-30T05:55:24.830802Z","end":"2026-04-30T05:55:25.056434Z","steps":["trace[467145928] 'agreement among raft nodes before linearized reading' (duration: 224.873141ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:25.056353Z","caller":"traceutil/trace.go:171","msg":"trace[577670132] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:348; }","duration":"175.554117ms","start":"2026-04-30T05:55:24.880749Z","end":"2026-04-30T05:55:25.056304Z","steps":["trace[577670132] 'agreement among raft nodes before linearized reading' (duration: 175.208945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:25.480743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.456925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T05:55:25.480988Z","caller":"traceutil/trace.go:171","msg":"trace[1596241744] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"258.563644ms","start":"2026-04-30T05:55:25.222388Z","end":"2026-04-30T05:55:25.480952Z","steps":["trace[1596241744] 'process raft request' (duration: 97.799556ms)","trace[1596241744] 'compare' (duration: 160.355384ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:25.481051Z","caller":"traceutil/trace.go:171","msg":"trace[448542071] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"258.09978ms","start":"2026-04-30T05:55:25.222927Z","end":"2026-04-30T05:55:25.481027Z","steps":["trace[448542071] 'process raft request' (duration: 257.920348ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:25.481115Z","caller":"traceutil/trace.go:171","msg":"trace[370809183] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:377; }","duration":"257.456054ms","start":"2026-04-30T05:55:25.223634Z","end":"2026-04-30T05:55:25.48109Z","steps":["trace[370809183] 'read index received' (duration: 6.908381ms)","trace[370809183] 'applied index is now lower than readState.Index' (duration: 250.544763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:25.481319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.673757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-30T05:55:25.481339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.793996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-30T05:55:25.481388Z","caller":"traceutil/trace.go:171","msg":"trace[19285262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"257.761837ms","start":"2026-04-30T05:55:25.223609Z","end":"2026-04-30T05:55:25.48137Z","steps":["trace[19285262] 'agreement among raft nodes before linearized reading' (duration: 257.613145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:25.481461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.661856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-30T05:55:25.481477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.299961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-30T05:55:25.481558Z","caller":"traceutil/trace.go:171","msg":"trace[2067229334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"257.783827ms","start":"2026-04-30T05:55:25.223734Z","end":"2026-04-30T05:55:25.481518Z","steps":["trace[2067229334] 'agreement among raft nodes before linearized reading' (duration: 257.536103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:25.481566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.498954ms","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-30T05:55:25.481667Z","caller":"traceutil/trace.go:171","msg":"trace[1303114235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"257.602435ms","start":"2026-04-30T05:55:25.22403Z","end":"2026-04-30T05:55:25.481633Z","steps":["trace[1303114235] 'agreement among raft nodes before linearized reading' (duration: 257.441543ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:25.481553Z","caller":"traceutil/trace.go:171","msg":"trace[1669370524] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:371; }","duration":"251.386522ms","start":"2026-04-30T05:55:25.230152Z","end":"2026-04-30T05:55:25.481538Z","steps":["trace[1669370524] 'agreement among raft nodes before linearized reading' (duration: 251.279981ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:25.481024Z","caller":"traceutil/trace.go:171","msg":"trace[1834014655] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"245.937105ms","start":"2026-04-30T05:55:25.235072Z","end":"2026-04-30T05:55:25.481009Z","steps":["trace[1834014655] 'process raft request' (duration: 245.858424ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:25.481394Z","caller":"traceutil/trace.go:171","msg":"trace[439729737] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:371; }","duration":"199.871487ms","start":"2026-04-30T05:55:25.281507Z","end":"2026-04-30T05:55:25.481379Z","steps":["trace[439729737] 'agreement among raft nodes before linearized reading' (duration: 199.766235ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:35.250923Z","caller":"traceutil/trace.go:171","msg":"trace[1305429797] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"244.152083ms","start":"2026-04-30T05:55:35.006745Z","end":"2026-04-30T05:55:35.250897Z","steps":["trace[1305429797] 'process raft request' (duration: 243.918705ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:35.394417Z","caller":"traceutil/trace.go:171","msg":"trace[895071088] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"137.90996ms","start":"2026-04-30T05:55:35.256477Z","end":"2026-04-30T05:55:35.394387Z","steps":["trace[895071088] 'process raft request' (duration: 97.998518ms)","trace[895071088] 'compare' (duration: 39.751775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:35.738085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.208415ms","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-30T05:55:35.738114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.956612ms","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-30T05:55:35.738179Z","caller":"traceutil/trace.go:171","msg":"trace[358701467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"101.34342ms","start":"2026-04-30T05:55:35.636807Z","end":"2026-04-30T05:55:35.73815Z","steps":["trace[358701467] 'range keys from in-memory index tree' (duration: 101.04832ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:35.738197Z","caller":"traceutil/trace.go:171","msg":"trace[614857493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"141.097716ms","start":"2026-04-30T05:55:35.597077Z","end":"2026-04-30T05:55:35.738175Z","steps":["trace[614857493] 'range keys from in-memory index tree' (duration: 140.776186ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:41.528907Z","caller":"traceutil/trace.go:171","msg":"trace[1538164964] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"106.828653ms","start":"2026-04-30T05:55:41.422052Z","end":"2026-04-30T05:55:41.52888Z","steps":["trace[1538164964] 'process raft request' (duration: 106.666658ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:41.889509Z","caller":"traceutil/trace.go:171","msg":"trace[1754386963] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"110.141036ms","start":"2026-04-30T05:55:41.779342Z","end":"2026-04-30T05:55:41.889483Z","steps":["trace[1754386963] 'read index received' (duration: 109.970591ms)","trace[1754386963] 'applied index is now lower than readState.Index' (duration: 169.515µs)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:41.889758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.390444ms","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-30T05:55:41.889835Z","caller":"traceutil/trace.go:171","msg":"trace[1151726851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"110.508738ms","start":"2026-04-30T05:55:41.779305Z","end":"2026-04-30T05:55:41.889814Z","steps":["trace[1151726851] 'agreement among raft nodes before linearized reading' (duration: 110.353602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:41.889835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.119254ms","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-30T05:55:41.88988Z","caller":"traceutil/trace.go:171","msg":"trace[416725355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:470; }","duration":"109.188407ms","start":"2026-04-30T05:55:41.78068Z","end":"2026-04-30T05:55:41.889868Z","steps":["trace[416725355] 'agreement among raft nodes before linearized reading' (duration: 109.104354ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:41.889943Z","caller":"traceutil/trace.go:171","msg":"trace[114113911] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"114.995808ms","start":"2026-04-30T05:55:41.774903Z","end":"2026-04-30T05:55:41.889899Z","steps":["trace[114113911] 'process raft request' (duration: 114.46045ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:44.072959Z","caller":"traceutil/trace.go:171","msg":"trace[606727178] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"179.444503ms","start":"2026-04-30T05:55:43.89349Z","end":"2026-04-30T05:55:44.072935Z","steps":["trace[606727178] 'read index received' (duration: 179.224457ms)","trace[606727178] 'applied index is now lower than readState.Index' (duration: 218.806µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:44.073009Z","caller":"traceutil/trace.go:171","msg":"trace[70524401] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"538.07154ms","start":"2026-04-30T05:55:43.534919Z","end":"2026-04-30T05:55:44.07299Z","steps":["trace[70524401] 'process raft request' (duration: 537.870604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:44.073114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:43.53491Z","time spent":"538.147912ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:55:44.073174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.759491ms","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-30T05:55:44.073225Z","caller":"traceutil/trace.go:171","msg":"trace[1812070126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"155.839084ms","start":"2026-04-30T05:55:43.917372Z","end":"2026-04-30T05:55:44.073211Z","steps":["trace[1812070126] 'agreement among raft nodes before linearized reading' (duration: 155.751431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:44.073211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.701042ms","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-30T05:55:44.073173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.12167ms","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-30T05:55:44.073365Z","caller":"traceutil/trace.go:171","msg":"trace[641465816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"170.333406ms","start":"2026-04-30T05:55:43.903005Z","end":"2026-04-30T05:55:44.073338Z","steps":["trace[641465816] 'agreement among raft nodes before linearized reading' (duration: 170.084509ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:44.073289Z","caller":"traceutil/trace.go:171","msg":"trace[1074326235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"179.816354ms","start":"2026-04-30T05:55:43.893456Z","end":"2026-04-30T05:55:44.073272Z","steps":["trace[1074326235] 'agreement among raft nodes before linearized reading' (duration: 179.629939ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:44.378091Z","caller":"traceutil/trace.go:171","msg":"trace[1459025805] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"300.731181ms","start":"2026-04-30T05:55:44.077338Z","end":"2026-04-30T05:55:44.378069Z","steps":["trace[1459025805] 'process raft request' (duration: 300.595307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:44.378223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:44.077316Z","time spent":"300.837513ms","remote":"127.0.0.1:53368","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-30T05:55:44.440728Z","caller":"traceutil/trace.go:171","msg":"trace[1806336231] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"359.720861ms","start":"2026-04-30T05:55:44.080991Z","end":"2026-04-30T05:55:44.440712Z","steps":["trace[1806336231] 'process raft request' (duration: 359.69493ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:44.440756Z","caller":"traceutil/trace.go:171","msg":"trace[292855363] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"362.584087ms","start":"2026-04-30T05:55:44.078152Z","end":"2026-04-30T05:55:44.440736Z","steps":["trace[292855363] 'process raft request' (duration: 362.433643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:44.440826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:44.080978Z","time spent":"359.797723ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:55:44.440869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:44.07814Z","time spent":"362.66034ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:55:44.667195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.862609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:55:44.667285Z","caller":"traceutil/trace.go:171","msg":"trace[130834479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"175.030835ms","start":"2026-04-30T05:55:44.492218Z","end":"2026-04-30T05:55:44.667249Z","steps":["trace[130834479] 'range keys from in-memory index tree' (duration: 174.791397ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:48.503796Z","caller":"traceutil/trace.go:171","msg":"trace[1634571089] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"109.59691ms","start":"2026-04-30T05:55:48.394173Z","end":"2026-04-30T05:55:48.50377Z","steps":["trace[1634571089] 'process raft request' (duration: 109.081456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:49.863555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.721928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T05:55:49.863728Z","caller":"traceutil/trace.go:171","msg":"trace[1171662031] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.356001356s","start":"2026-04-30T05:55:48.507712Z","end":"2026-04-30T05:55:49.863714Z","steps":["trace[1171662031] 'process raft request' (duration: 910.04473ms)","trace[1171662031] 'compare' (duration: 445.250195ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:49.863804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:48.507689Z","time spent":"1.356085628s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:55:49.864047Z","caller":"traceutil/trace.go:171","msg":"trace[1687918753] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:537; }","duration":"559.713061ms","start":"2026-04-30T05:55:49.304292Z","end":"2026-04-30T05:55:49.864006Z","steps":["trace[1687918753] 'read index received' (duration: 113.485238ms)","trace[1687918753] 'applied index is now lower than readState.Index' (duration: 446.226123ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:49.864221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.934707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.103\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-30T05:55:49.864274Z","caller":"traceutil/trace.go:171","msg":"trace[933301480] range","detail":"{range_begin:/registry/masterleases/199.19.213.103; range_end:; response_count:1; response_revision:520; }","duration":"559.986569ms","start":"2026-04-30T05:55:49.304274Z","end":"2026-04-30T05:55:49.864261Z","steps":["trace[933301480] 'agreement among raft nodes before linearized reading' (duration: 559.898837ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:49.864116Z","caller":"traceutil/trace.go:171","msg":"trace[925953570] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.355813459s","start":"2026-04-30T05:55:48.508268Z","end":"2026-04-30T05:55:49.864081Z","steps":["trace[925953570] 'process raft request' (duration: 1.355375297s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:49.864304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:49.304169Z","time spent":"560.128162ms","remote":"127.0.0.1:53148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.103\" "} {"level":"warn","ts":"2026-04-30T05:55:49.864781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:48.508246Z","time spent":"1.356109128s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:55:50.499299Z","caller":"traceutil/trace.go:171","msg":"trace[142677335] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"145.378538ms","start":"2026-04-30T05:55:50.353864Z","end":"2026-04-30T05:55:50.499243Z","steps":["trace[142677335] 'process raft request' (duration: 125.056902ms)","trace[142677335] 'compare' (duration: 20.213462ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:50.500258Z","caller":"traceutil/trace.go:171","msg":"trace[140909779] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"136.031163ms","start":"2026-04-30T05:55:50.364205Z","end":"2026-04-30T05:55:50.500236Z","steps":["trace[140909779] 'process raft request' (duration: 135.659303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:51.727346Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.173940299s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T05:55:51.760163Z","caller":"traceutil/trace.go:171","msg":"trace[693018142] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.207122299s","start":"2026-04-30T05:55:50.553003Z","end":"2026-04-30T05:55:51.760125Z","steps":["trace[693018142] 'process raft request' (duration: 1.174650689s)","trace[693018142] 'compare' (duration: 31.801092ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:55:51.760191Z","caller":"traceutil/trace.go:171","msg":"trace[1318669133] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.192963278s","start":"2026-04-30T05:55:50.567211Z","end":"2026-04-30T05:55:51.760174Z","steps":["trace[1318669133] 'process raft request' (duration: 1.192684261s)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:51.76022Z","caller":"traceutil/trace.go:171","msg":"trace[30413815] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.207177131s","start":"2026-04-30T05:55:50.55301Z","end":"2026-04-30T05:55:51.760187Z","steps":["trace[30413815] 'process raft request' (duration: 1.20677418s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:51.760303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:50.567193Z","time spent":"1.19303973s","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:55:51.760299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:50.552982Z","time spent":"1.207250502s","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T05:55:51.760371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:50.552986Z","time spent":"1.207300143s","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:55:51.760443Z","caller":"traceutil/trace.go:171","msg":"trace[1755196796] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.187561264s","start":"2026-04-30T05:55:50.572866Z","end":"2026-04-30T05:55:51.760428Z","steps":["trace[1755196796] 'process raft request' (duration: 1.187176404s)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:51.760447Z","caller":"traceutil/trace.go:171","msg":"trace[1486382205] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:579; }","duration":"267.350894ms","start":"2026-04-30T05:55:51.493054Z","end":"2026-04-30T05:55:51.760404Z","steps":["trace[1486382205] 'read index received' (duration: 234.628447ms)","trace[1486382205] 'applied index is now lower than readState.Index' (duration: 32.721047ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:51.760525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:50.572842Z","time spent":"1.187633527s","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:55:51.7606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.527619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:55:51.760639Z","caller":"traceutil/trace.go:171","msg":"trace[576358739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"267.616452ms","start":"2026-04-30T05:55:51.49301Z","end":"2026-04-30T05:55:51.760627Z","steps":["trace[576358739] 'agreement among raft nodes before linearized reading' (duration: 267.505009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:52.324177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.550749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T05:55:52.324352Z","caller":"traceutil/trace.go:171","msg":"trace[1255572318] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"494.458966ms","start":"2026-04-30T05:55:51.829871Z","end":"2026-04-30T05:55:52.32433Z","steps":["trace[1255572318] 'process raft request' (duration: 86.66569ms)","trace[1255572318] 'compare' (duration: 407.401305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:52.324418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:51.829856Z","time spent":"494.532078ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:55:52.324833Z","caller":"traceutil/trace.go:171","msg":"trace[622049850] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"491.549154ms","start":"2026-04-30T05:55:51.833268Z","end":"2026-04-30T05:55:52.324818Z","steps":["trace[622049850] 'process raft request' (duration: 491.010649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:52.324929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:51.83326Z","time spent":"491.633286ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:55:52.325129Z","caller":"traceutil/trace.go:171","msg":"trace[927139758] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"484.137757ms","start":"2026-04-30T05:55:51.840976Z","end":"2026-04-30T05:55:52.325114Z","steps":["trace[927139758] 'process raft request' (duration: 483.708954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:52.325199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:51.840949Z","time spent":"484.215788ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T05:55:52.325082Z","caller":"traceutil/trace.go:171","msg":"trace[1206592372] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:586; }","duration":"456.78581ms","start":"2026-04-30T05:55:51.868254Z","end":"2026-04-30T05:55:52.32504Z","steps":["trace[1206592372] 'read index received' (duration: 48.376686ms)","trace[1206592372] 'applied index is now lower than readState.Index' (duration: 408.407734ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:55:52.325388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.664376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-30T05:55:52.325388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.103929ms","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-30T05:55:52.325422Z","caller":"traceutil/trace.go:171","msg":"trace[227328671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"456.714137ms","start":"2026-04-30T05:55:51.868699Z","end":"2026-04-30T05:55:52.325413Z","steps":["trace[227328671] 'agreement among raft nodes before linearized reading' (duration: 456.635925ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:55:52.325446Z","caller":"traceutil/trace.go:171","msg":"trace[385565887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"457.218372ms","start":"2026-04-30T05:55:51.868216Z","end":"2026-04-30T05:55:52.325434Z","steps":["trace[385565887] 'agreement among raft nodes before linearized reading' (duration: 456.945324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:52.325502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:51.868203Z","time spent":"457.289974ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T05:55:52.325508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.218699ms","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-30T05:55:52.325545Z","caller":"traceutil/trace.go:171","msg":"trace[1031589020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"189.287381ms","start":"2026-04-30T05:55:52.136249Z","end":"2026-04-30T05:55:52.325537Z","steps":["trace[1031589020] 'agreement among raft nodes before linearized reading' (duration: 189.231389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:55:52.325451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:51.868691Z","time spent":"456.754068ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-30T05:55:58.666548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932999014315359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T05:55:59.16676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932999014315359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T05:55:59.667197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932999014315359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T05:56:00.152375Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.105403366s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T05:56:00.152949Z","caller":"traceutil/trace.go:171","msg":"trace[1069411574] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"1.987188991s","start":"2026-04-30T05:55:58.165734Z","end":"2026-04-30T05:56:00.152923Z","steps":["trace[1069411574] 'read index received' (duration: 1.986805591s)","trace[1069411574] 'applied index is now lower than readState.Index' (duration: 382.03µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:00.153019Z","caller":"traceutil/trace.go:171","msg":"trace[1374455931] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"2.106116394s","start":"2026-04-30T05:55:58.046858Z","end":"2026-04-30T05:56:00.152975Z","steps":["trace[1374455931] 'process raft request' (duration: 2.105828917s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:00.153234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:58.046826Z","time spent":"2.1063306s","remote":"127.0.0.1:53586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T05:56:00.153435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.783127228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-30T05:56:00.153532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.729875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.103\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-30T05:56:00.153557Z","caller":"traceutil/trace.go:171","msg":"trace[1397812629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.783268761s","start":"2026-04-30T05:55:58.370231Z","end":"2026-04-30T05:56:00.1535Z","steps":["trace[1397812629] 'agreement among raft nodes before linearized reading' (duration: 1.782968694s)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:00.153593Z","caller":"traceutil/trace.go:171","msg":"trace[1326767926] range","detail":"{range_begin:/registry/masterleases/199.19.213.103; range_end:; response_count:1; response_revision:631; }","duration":"848.822297ms","start":"2026-04-30T05:55:59.304753Z","end":"2026-04-30T05:56:00.153576Z","steps":["trace[1326767926] 'agreement among raft nodes before linearized reading' (duration: 848.739075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:00.153644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:59.304725Z","time spent":"848.908059ms","remote":"127.0.0.1:53148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.103\" "} {"level":"warn","ts":"2026-04-30T05:56:00.153647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:58.370212Z","time spent":"1.783419304s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-30T05:56:00.153341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.987573662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b22r6\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-30T05:56:00.153438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577564374s","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-30T05:56:00.153941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.774961464s","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-30T05:56:00.154025Z","caller":"traceutil/trace.go:171","msg":"trace[104171894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"1.775053677s","start":"2026-04-30T05:55:58.378942Z","end":"2026-04-30T05:56:00.153995Z","steps":["trace[104171894] 'agreement among raft nodes before linearized reading' (duration: 1.77480168s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:00.154047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778345901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-30T05:56:00.154055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.663728352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:00.154084Z","caller":"traceutil/trace.go:171","msg":"trace[1604371906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.778423413s","start":"2026-04-30T05:55:58.375651Z","end":"2026-04-30T05:56:00.154074Z","steps":["trace[1604371906] 'agreement among raft nodes before linearized reading' (duration: 1.77829004s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:00.154083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:58.37893Z","time spent":"1.775144038s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-30T05:56:00.154106Z","caller":"traceutil/trace.go:171","msg":"trace[210908440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"1.663800773s","start":"2026-04-30T05:55:58.490291Z","end":"2026-04-30T05:56:00.154092Z","steps":["trace[210908440] 'agreement among raft nodes before linearized reading' (duration: 1.663721511s)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:00.153983Z","caller":"traceutil/trace.go:171","msg":"trace[1803366744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"1.578144288s","start":"2026-04-30T05:55:58.575803Z","end":"2026-04-30T05:56:00.153947Z","steps":["trace[1803366744] 'agreement among raft nodes before linearized reading' (duration: 1.577451351s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:00.154137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:58.37563Z","time spent":"1.778497786s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T05:56:00.154153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:58.49028Z","time spent":"1.663863074s","remote":"127.0.0.1:53116","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-30T05:56:00.153891Z","caller":"traceutil/trace.go:171","msg":"trace[244007464] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b22r6; range_end:; response_count:1; response_revision:631; }","duration":"1.988143136s","start":"2026-04-30T05:55:58.165694Z","end":"2026-04-30T05:56:00.153837Z","steps":["trace[244007464] 'agreement among raft nodes before linearized reading' (duration: 1.987365015s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:00.154297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:58.575789Z","time spent":"1.578471216s","remote":"127.0.0.1:53368","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-30T05:56:00.154372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:55:58.165674Z","time spent":"1.98868633s","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-b22r6\" "} {"level":"warn","ts":"2026-04-30T05:56:00.664534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932999014315362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T05:56:02.164936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001018715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-30T05:56:02.165027Z","caller":"traceutil/trace.go:171","msg":"trace[195360042] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001154258s","start":"2026-04-30T05:56:00.163852Z","end":"2026-04-30T05:56:02.165006Z","steps":["trace[195360042] 'agreement among raft nodes before linearized reading' (duration: 2.001037795s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:02.165103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:00.163843Z","time spent":"2.00123533s","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/30 05:56:02 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-30T05:56:02.913692Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.816883376s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-30T05:56:04.165741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000292626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-30T05:56:04.165827Z","caller":"traceutil/trace.go:171","msg":"trace[1878549156] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000472121s","start":"2026-04-30T05:56:02.165336Z","end":"2026-04-30T05:56:04.165808Z","steps":["trace[1878549156] 'agreement among raft nodes before linearized reading' (duration: 2.000328977s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:04.165872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:02.165314Z","time spent":"2.000549483s","remote":"127.0.0.1:53116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-30T05:56:05.158658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:00.159885Z","time spent":"4.998766409s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-30T05:56:05.158876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:00.159772Z","time spent":"4.999098438s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/30 05:56:05 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-30T05:56:05.421054Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.205628951s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-30T05:56:05.825359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.995614489s","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-30T05:56:05.825484Z","caller":"traceutil/trace.go:171","msg":"trace[1828863912] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:631; }","duration":"2.995747372s","start":"2026-04-30T05:56:02.829718Z","end":"2026-04-30T05:56:05.825465Z","steps":["trace[1828863912] 'range keys from in-memory index tree' (duration: 2.995599809s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:05.826468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.729714656s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-30T05:56:05.826637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:00.155049Z","time spent":"5.671584932s","remote":"127.0.0.1:53148","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-30T05:56:05.827218Z","caller":"traceutil/trace.go:171","msg":"trace[161867664] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"2.611969208s","start":"2026-04-30T05:56:03.215235Z","end":"2026-04-30T05:56:05.827204Z","steps":["trace[161867664] 'process raft request' (duration: 2.611922587s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:05.827341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:03.21521Z","time spent":"2.612051251s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:05.827481Z","caller":"traceutil/trace.go:171","msg":"trace[637481563] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"5.666163043s","start":"2026-04-30T05:56:00.161308Z","end":"2026-04-30T05:56:05.827471Z","steps":["trace[637481563] 'process raft request' (duration: 5.665726322s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:05.827546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:00.161301Z","time spent":"5.666208094s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:05.82765Z","caller":"traceutil/trace.go:171","msg":"trace[1925474919] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"5.665194147s","start":"2026-04-30T05:56:00.162423Z","end":"2026-04-30T05:56:05.827617Z","steps":["trace[1925474919] 'process raft request' (duration: 5.664648064s)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:05.827669Z","caller":"traceutil/trace.go:171","msg":"trace[708907264] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:653; }","duration":"5.663764661s","start":"2026-04-30T05:56:00.163879Z","end":"2026-04-30T05:56:05.827643Z","steps":["trace[708907264] 'read index received' (duration: 932.883485ms)","trace[708907264] 'applied index is now lower than readState.Index' (duration: 4.730879336s)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:05.827652Z","caller":"traceutil/trace.go:171","msg":"trace[858147374] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"5.654423682s","start":"2026-04-30T05:56:00.173174Z","end":"2026-04-30T05:56:05.827598Z","steps":["trace[858147374] 'process raft request' (duration: 5.65392863s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:05.827808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:00.173162Z","time spent":"5.654607776s","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:56:05.827816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:00.1624Z","time spent":"5.665330791s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:05.911354Z","caller":"traceutil/trace.go:171","msg":"trace[254334395] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.741463411s","start":"2026-04-30T05:56:04.169869Z","end":"2026-04-30T05:56:05.911332Z","steps":["trace[254334395] 'process raft request' (duration: 1.741330588s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:05.911489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:04.169843Z","time spent":"1.741577304s","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T05:56:06.015601Z","caller":"traceutil/trace.go:171","msg":"trace[186446178] transaction","detail":"{read_only:false; number_of_response:1; response_revision:639; }","duration":"187.833142ms","start":"2026-04-30T05:56:05.827725Z","end":"2026-04-30T05:56:06.015559Z","steps":["trace[186446178] 'process raft request' (duration: 187.729179ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:06.015651Z","caller":"traceutil/trace.go:171","msg":"trace[1990027847] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"100.766272ms","start":"2026-04-30T05:56:05.914853Z","end":"2026-04-30T05:56:06.015619Z","steps":["trace[1990027847] 'process raft request' (duration: 100.661759ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:06.015723Z","caller":"traceutil/trace.go:171","msg":"trace[319390210] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"187.964736ms","start":"2026-04-30T05:56:05.827723Z","end":"2026-04-30T05:56:06.015688Z","steps":["trace[319390210] 'read index received' (duration: 83.470473ms)","trace[319390210] 'applied index is now lower than readState.Index' (duration: 104.491053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:06.015799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.84253128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:06.015854Z","caller":"traceutil/trace.go:171","msg":"trace[2086279615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"1.842632982s","start":"2026-04-30T05:56:04.173208Z","end":"2026-04-30T05:56:06.015841Z","steps":["trace[2086279615] 'agreement among raft nodes before linearized reading' (duration: 1.842506189s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:06.015859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.850479974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T05:56:06.015897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:04.173196Z","time spent":"1.842693313s","remote":"127.0.0.1:53120","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-30T05:56:06.015905Z","caller":"traceutil/trace.go:171","msg":"trace[1805128719] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:640; }","duration":"5.850544566s","start":"2026-04-30T05:56:00.165342Z","end":"2026-04-30T05:56:06.015887Z","steps":["trace[1805128719] 'agreement among raft nodes before linearized reading' (duration: 5.850453724s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:06.015946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:00.165337Z","time spent":"5.850601628s","remote":"127.0.0.1:53354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-30T05:56:08.333393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932999014315410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T05:56:08.575742Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.003827367s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T05:56:08.576038Z","caller":"traceutil/trace.go:171","msg":"trace[1343162207] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"742.904051ms","start":"2026-04-30T05:56:07.833113Z","end":"2026-04-30T05:56:08.576017Z","steps":["trace[1343162207] 'read index received' (duration: 742.784979ms)","trace[1343162207] 'applied index is now lower than readState.Index' (duration: 118.302µs)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:08.576233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.104446ms","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-30T05:56:08.576258Z","caller":"traceutil/trace.go:171","msg":"trace[2007811780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"743.167158ms","start":"2026-04-30T05:56:07.833082Z","end":"2026-04-30T05:56:08.576249Z","steps":["trace[2007811780] 'agreement among raft nodes before linearized reading' (duration: 743.044145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:08.576251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.88353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-30T05:56:08.576283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:07.83307Z","time spent":"743.207258ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-30T05:56:08.5763Z","caller":"traceutil/trace.go:171","msg":"trace[1943075347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"742.995334ms","start":"2026-04-30T05:56:07.83329Z","end":"2026-04-30T05:56:08.576285Z","steps":["trace[1943075347] 'agreement among raft nodes before linearized reading' (duration: 742.8549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:08.576334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:07.833254Z","time spent":"743.072646ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T05:56:08.576312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.835165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b22r6\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-04-30T05:56:08.576387Z","caller":"traceutil/trace.go:171","msg":"trace[2144977978] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b22r6; range_end:; response_count:1; response_revision:659; }","duration":"372.947027ms","start":"2026-04-30T05:56:08.203421Z","end":"2026-04-30T05:56:08.576368Z","steps":["trace[2144977978] 'agreement among raft nodes before linearized reading' (duration: 372.753362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:08.576425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:08.203405Z","time spent":"373.012949ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17547,"request content":"key:\"/registry/pods/kube-system/cilium-b22r6\" "} {"level":"info","ts":"2026-04-30T05:56:08.576111Z","caller":"traceutil/trace.go:171","msg":"trace[1963552099] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.004269037s","start":"2026-04-30T05:56:07.571809Z","end":"2026-04-30T05:56:08.576078Z","steps":["trace[1963552099] 'process raft request' (duration: 1.004083863s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:08.576763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:07.571793Z","time spent":"1.004861741s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:09.374997Z","caller":"traceutil/trace.go:171","msg":"trace[1238820580] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"157.862582ms","start":"2026-04-30T05:56:09.217108Z","end":"2026-04-30T05:56:09.37497Z","steps":["trace[1238820580] 'process raft request' (duration: 154.282138ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:09.386551Z","caller":"traceutil/trace.go:171","msg":"trace[111008574] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"133.840029ms","start":"2026-04-30T05:56:09.252685Z","end":"2026-04-30T05:56:09.386525Z","steps":["trace[111008574] 'process raft request' (duration: 133.505701ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:09.386972Z","caller":"traceutil/trace.go:171","msg":"trace[1226963579] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"164.889796ms","start":"2026-04-30T05:56:09.22152Z","end":"2026-04-30T05:56:09.386409Z","steps":["trace[1226963579] 'process raft request' (duration: 164.530438ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:09.53432Z","caller":"traceutil/trace.go:171","msg":"trace[1165194860] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"141.99649ms","start":"2026-04-30T05:56:09.3923Z","end":"2026-04-30T05:56:09.534297Z","steps":["trace[1165194860] 'process raft request' (duration: 96.612915ms)","trace[1165194860] 'compare' (duration: 45.285423ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:09.671766Z","caller":"traceutil/trace.go:171","msg":"trace[1563020487] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"219.413024ms","start":"2026-04-30T05:56:09.452331Z","end":"2026-04-30T05:56:09.671744Z","steps":["trace[1563020487] 'process raft request' (duration: 219.362053ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:09.671877Z","caller":"traceutil/trace.go:171","msg":"trace[1421298539] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"250.032973ms","start":"2026-04-30T05:56:09.421824Z","end":"2026-04-30T05:56:09.671857Z","steps":["trace[1421298539] 'process raft request' (duration: 249.806078ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:09.672346Z","caller":"traceutil/trace.go:171","msg":"trace[445759636] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"265.254549ms","start":"2026-04-30T05:56:09.407054Z","end":"2026-04-30T05:56:09.672308Z","steps":["trace[445759636] 'process raft request' (duration: 261.02422ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:09.67935Z","caller":"traceutil/trace.go:171","msg":"trace[689067915] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"138.203431ms","start":"2026-04-30T05:56:09.541124Z","end":"2026-04-30T05:56:09.679328Z","steps":["trace[689067915] 'process raft request' (duration: 137.834082ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:09.791338Z","caller":"traceutil/trace.go:171","msg":"trace[1306412189] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"105.398811ms","start":"2026-04-30T05:56:09.685916Z","end":"2026-04-30T05:56:09.791314Z","steps":["trace[1306412189] 'process raft request' (duration: 92.305915ms)","trace[1306412189] 'compare' (duration: 12.999994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:10.156795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.595561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T05:56:10.156918Z","caller":"traceutil/trace.go:171","msg":"trace[1606815075] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"357.379265ms","start":"2026-04-30T05:56:09.799516Z","end":"2026-04-30T05:56:10.156895Z","steps":["trace[1606815075] 'process raft request' (duration: 132.594649ms)","trace[1606815075] 'compare' (duration: 224.474999ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:10.156985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:09.799494Z","time spent":"357.458967ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:10.208414Z","caller":"traceutil/trace.go:171","msg":"trace[60765267] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"408.056982ms","start":"2026-04-30T05:56:09.800339Z","end":"2026-04-30T05:56:10.208396Z","steps":["trace[60765267] 'process raft request' (duration: 407.914899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:10.208554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:09.800307Z","time spent":"408.187605ms","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4387,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:10.208712Z","caller":"traceutil/trace.go:171","msg":"trace[1939204730] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:720; }","duration":"357.518507ms","start":"2026-04-30T05:56:09.851161Z","end":"2026-04-30T05:56:10.20868Z","steps":["trace[1939204730] 'read index received' (duration: 81.026419ms)","trace[1939204730] 'applied index is now lower than readState.Index' (duration: 276.489698ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:10.208711Z","caller":"traceutil/trace.go:171","msg":"trace[1948935803] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"403.26179ms","start":"2026-04-30T05:56:09.805421Z","end":"2026-04-30T05:56:10.208683Z","steps":["trace[1948935803] 'process raft request' (duration: 402.936613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:10.208888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:09.805403Z","time spent":"403.410143ms","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T05:56:10.20899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.786054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:10.20911Z","caller":"traceutil/trace.go:171","msg":"trace[215012312] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"357.989339ms","start":"2026-04-30T05:56:09.851104Z","end":"2026-04-30T05:56:10.209094Z","steps":["trace[215012312] 'agreement among raft nodes before linearized reading' (duration: 357.727992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:10.209177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.772555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T05:56:10.209182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:09.851084Z","time spent":"358.085661ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-04-30T05:56:10.20937Z","caller":"traceutil/trace.go:171","msg":"trace[418521238] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"158.060992ms","start":"2026-04-30T05:56:10.051293Z","end":"2026-04-30T05:56:10.209354Z","steps":["trace[418521238] 'agreement among raft nodes before linearized reading' (duration: 157.755805ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:10.507105Z","caller":"traceutil/trace.go:171","msg":"trace[574437951] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"158.687276ms","start":"2026-04-30T05:56:10.348387Z","end":"2026-04-30T05:56:10.507075Z","steps":["trace[574437951] 'process raft request' (duration: 112.142795ms)","trace[574437951] 'compare' (duration: 46.401047ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:10.827627Z","caller":"traceutil/trace.go:171","msg":"trace[283281093] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"238.162992ms","start":"2026-04-30T05:56:10.589441Z","end":"2026-04-30T05:56:10.827604Z","steps":["trace[283281093] 'read index received' (duration: 237.979148ms)","trace[283281093] 'applied index is now lower than readState.Index' (duration: 182.864µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:10.827725Z","caller":"traceutil/trace.go:171","msg":"trace[392734219] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"308.533096ms","start":"2026-04-30T05:56:10.519165Z","end":"2026-04-30T05:56:10.827698Z","steps":["trace[392734219] 'process raft request' (duration: 308.305021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:10.82795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.446179ms","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-30T05:56:10.828045Z","caller":"traceutil/trace.go:171","msg":"trace[1003806199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:703; }","duration":"238.640784ms","start":"2026-04-30T05:56:10.589393Z","end":"2026-04-30T05:56:10.828034Z","steps":["trace[1003806199] 'agreement among raft nodes before linearized reading' (duration: 238.336326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:10.828174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.521927ms","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-30T05:56:10.828227Z","caller":"traceutil/trace.go:171","msg":"trace[1204836723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:703; }","duration":"234.59041ms","start":"2026-04-30T05:56:10.593624Z","end":"2026-04-30T05:56:10.828215Z","steps":["trace[1204836723] 'agreement among raft nodes before linearized reading' (duration: 234.414286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:10.828196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.767077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T05:56:10.827901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:10.519146Z","time spent":"308.642808ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:10.828384Z","caller":"traceutil/trace.go:171","msg":"trace[1520618917] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"176.94218ms","start":"2026-04-30T05:56:10.651382Z","end":"2026-04-30T05:56:10.828324Z","steps":["trace[1520618917] 'agreement among raft nodes before linearized reading' (duration: 176.722165ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:10.962459Z","caller":"traceutil/trace.go:171","msg":"trace[1879738235] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:729; }","duration":"111.904689ms","start":"2026-04-30T05:56:10.850538Z","end":"2026-04-30T05:56:10.962443Z","steps":["trace[1879738235] 'read index received' (duration: 872.9µs)","trace[1879738235] 'applied index is now lower than readState.Index' (duration: 111.030909ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:10.962604Z","caller":"traceutil/trace.go:171","msg":"trace[15587833] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"128.374791ms","start":"2026-04-30T05:56:10.834203Z","end":"2026-04-30T05:56:10.962577Z","steps":["trace[15587833] 'process raft request' (duration: 101.439445ms)","trace[15587833] 'compare' (duration: 26.694251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:10.962705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.136304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-30T05:56:10.962762Z","caller":"traceutil/trace.go:171","msg":"trace[497570929] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"112.237497ms","start":"2026-04-30T05:56:10.850492Z","end":"2026-04-30T05:56:10.962729Z","steps":["trace[497570929] 'agreement among raft nodes before linearized reading' (duration: 112.013432ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:11.138405Z","caller":"traceutil/trace.go:171","msg":"trace[295862421] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"103.108433ms","start":"2026-04-30T05:56:11.035264Z","end":"2026-04-30T05:56:11.138373Z","steps":["trace[295862421] 'process raft request' (duration: 30.870261ms)","trace[295862421] 'compare' (duration: 71.987886ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:11.316507Z","caller":"traceutil/trace.go:171","msg":"trace[72443522] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"174.041915ms","start":"2026-04-30T05:56:11.142436Z","end":"2026-04-30T05:56:11.316478Z","steps":["trace[72443522] 'process raft request' (duration: 107.833442ms)","trace[72443522] 'compare' (duration: 66.039249ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:11.316633Z","caller":"traceutil/trace.go:171","msg":"trace[1191047421] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:732; }","duration":"138.393015ms","start":"2026-04-30T05:56:11.178219Z","end":"2026-04-30T05:56:11.316612Z","steps":["trace[1191047421] 'read index received' (duration: 72.057228ms)","trace[1191047421] 'applied index is now lower than readState.Index' (duration: 66.335037ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:11.316679Z","caller":"traceutil/trace.go:171","msg":"trace[1998073965] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"161.524087ms","start":"2026-04-30T05:56:11.155143Z","end":"2026-04-30T05:56:11.316667Z","steps":["trace[1998073965] 'process raft request' (duration: 161.289221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:11.317014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.108969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-30T05:56:11.317308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.07699ms","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-30T05:56:11.317737Z","caller":"traceutil/trace.go:171","msg":"trace[107292043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:708; }","duration":"135.844216ms","start":"2026-04-30T05:56:11.181852Z","end":"2026-04-30T05:56:11.317696Z","steps":["trace[107292043] 'agreement among raft nodes before linearized reading' (duration: 134.909945ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:11.317796Z","caller":"traceutil/trace.go:171","msg":"trace[2039835282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:708; }","duration":"139.603472ms","start":"2026-04-30T05:56:11.178182Z","end":"2026-04-30T05:56:11.317785Z","steps":["trace[2039835282] 'agreement among raft nodes before linearized reading' (duration: 138.502848ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:12.821439Z","caller":"traceutil/trace.go:171","msg":"trace[911706154] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"326.415603ms","start":"2026-04-30T05:56:12.494997Z","end":"2026-04-30T05:56:12.821412Z","steps":["trace[911706154] 'read index received' (duration: 326.232809ms)","trace[911706154] 'applied index is now lower than readState.Index' (duration: 181.544µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:12.821558Z","caller":"traceutil/trace.go:171","msg":"trace[1797555478] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"329.85192ms","start":"2026-04-30T05:56:12.491681Z","end":"2026-04-30T05:56:12.821533Z","steps":["trace[1797555478] 'process raft request' (duration: 329.533493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:12.821775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:12.491654Z","time spent":"330.040776ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T05:56:12.822089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.044497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:12.822206Z","caller":"traceutil/trace.go:171","msg":"trace[234055109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"327.239361ms","start":"2026-04-30T05:56:12.494939Z","end":"2026-04-30T05:56:12.822178Z","steps":["trace[234055109] 'agreement among raft nodes before linearized reading' (duration: 326.847783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:12.822334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:12.494912Z","time spent":"327.409016ms","remote":"127.0.0.1:53116","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-30T05:56:12.936445Z","caller":"traceutil/trace.go:171","msg":"trace[514298729] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"250.144343ms","start":"2026-04-30T05:56:12.686258Z","end":"2026-04-30T05:56:12.936402Z","steps":["trace[514298729] 'process raft request' (duration: 250.00863ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:12.936779Z","caller":"traceutil/trace.go:171","msg":"trace[898121688] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"381.385957ms","start":"2026-04-30T05:56:12.555356Z","end":"2026-04-30T05:56:12.936742Z","steps":["trace[898121688] 'process raft request' (duration: 380.715981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:12.936965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:12.555321Z","time spent":"381.544851ms","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T05:56:12.995794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.967417ms","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-30T05:56:12.995928Z","caller":"traceutil/trace.go:171","msg":"trace[4489845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:724; }","duration":"142.141132ms","start":"2026-04-30T05:56:12.853769Z","end":"2026-04-30T05:56:12.99591Z","steps":["trace[4489845] 'agreement among raft nodes before linearized reading' (duration: 141.891676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:13.212246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.518219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-30T05:56:13.212329Z","caller":"traceutil/trace.go:171","msg":"trace[1068559228] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:726; }","duration":"161.688964ms","start":"2026-04-30T05:56:13.050621Z","end":"2026-04-30T05:56:13.21231Z","steps":["trace[1068559228] 'range keys from in-memory index tree' (duration: 161.296504ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:13.298976Z","caller":"traceutil/trace.go:171","msg":"trace[2134075723] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"230.554968ms","start":"2026-04-30T05:56:13.068397Z","end":"2026-04-30T05:56:13.298952Z","steps":["trace[2134075723] 'process raft request' (duration: 230.353584ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:13.468264Z","caller":"traceutil/trace.go:171","msg":"trace[1150416274] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"396.682612ms","start":"2026-04-30T05:56:13.071548Z","end":"2026-04-30T05:56:13.46823Z","steps":["trace[1150416274] 'process raft request' (duration: 396.197301ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:13.4685Z","caller":"traceutil/trace.go:171","msg":"trace[1179403604] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"216.984703ms","start":"2026-04-30T05:56:13.251437Z","end":"2026-04-30T05:56:13.468422Z","steps":["trace[1179403604] 'read index received' (duration: 47.637307ms)","trace[1179403604] 'applied index is now lower than readState.Index' (duration: 169.344306ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:13.468526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:13.071522Z","time spent":"396.878105ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:13.468718Z","caller":"traceutil/trace.go:171","msg":"trace[1630005506] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"195.578159ms","start":"2026-04-30T05:56:13.273107Z","end":"2026-04-30T05:56:13.468685Z","steps":["trace[1630005506] 'process raft request' (duration: 195.053646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:13.468869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.42686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b22r6\" ","response":"range_response_count:1 size:18337"} {"level":"warn","ts":"2026-04-30T05:56:13.468891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.596611ms","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-30T05:56:13.468923Z","caller":"traceutil/trace.go:171","msg":"trace[649596242] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b22r6; range_end:; response_count:1; response_revision:729; }","duration":"197.512973ms","start":"2026-04-30T05:56:13.271394Z","end":"2026-04-30T05:56:13.468907Z","steps":["trace[649596242] 'agreement among raft nodes before linearized reading' (duration: 197.300198ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:13.468935Z","caller":"traceutil/trace.go:171","msg":"trace[33811134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"130.658883ms","start":"2026-04-30T05:56:13.338264Z","end":"2026-04-30T05:56:13.468923Z","steps":["trace[33811134] 'agreement among raft nodes before linearized reading' (duration: 130.490819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:13.46921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.709939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-30T05:56:13.46925Z","caller":"traceutil/trace.go:171","msg":"trace[1951501442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:729; }","duration":"140.772191ms","start":"2026-04-30T05:56:13.328467Z","end":"2026-04-30T05:56:13.469239Z","steps":["trace[1951501442] 'agreement among raft nodes before linearized reading' (duration: 140.648048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:13.469419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.499855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-30T05:56:13.46948Z","caller":"traceutil/trace.go:171","msg":"trace[137156835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:729; }","duration":"148.585997ms","start":"2026-04-30T05:56:13.320882Z","end":"2026-04-30T05:56:13.469467Z","steps":["trace[137156835] 'agreement among raft nodes before linearized reading' (duration: 148.410023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:13.469745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.311223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-30T05:56:13.469814Z","caller":"traceutil/trace.go:171","msg":"trace[1552349779] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:729; }","duration":"218.442425ms","start":"2026-04-30T05:56:13.251357Z","end":"2026-04-30T05:56:13.4698Z","steps":["trace[1552349779] 'agreement among raft nodes before linearized reading' (duration: 217.29823ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:13.61418Z","caller":"traceutil/trace.go:171","msg":"trace[1391614024] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"140.052075ms","start":"2026-04-30T05:56:13.474097Z","end":"2026-04-30T05:56:13.614149Z","steps":["trace[1391614024] 'process raft request' (duration: 138.232993ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:13.614875Z","caller":"traceutil/trace.go:171","msg":"trace[1834657461] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"138.670163ms","start":"2026-04-30T05:56:13.476189Z","end":"2026-04-30T05:56:13.61486Z","steps":["trace[1834657461] 'process raft request' (duration: 138.479809ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:13.615019Z","caller":"traceutil/trace.go:171","msg":"trace[198287036] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:755; }","duration":"125.875274ms","start":"2026-04-30T05:56:13.48911Z","end":"2026-04-30T05:56:13.614985Z","steps":["trace[198287036] 'read index received' (duration: 29.423294ms)","trace[198287036] 'applied index is now lower than readState.Index' (duration: 96.450399ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:13.615137Z","caller":"traceutil/trace.go:171","msg":"trace[533786499] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"131.990832ms","start":"2026-04-30T05:56:13.483118Z","end":"2026-04-30T05:56:13.615109Z","steps":["trace[533786499] 'process raft request' (duration: 131.686356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:13.615209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.066989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:13.615258Z","caller":"traceutil/trace.go:171","msg":"trace[329541157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"126.16614ms","start":"2026-04-30T05:56:13.489079Z","end":"2026-04-30T05:56:13.615245Z","steps":["trace[329541157] 'agreement among raft nodes before linearized reading' (duration: 126.064258ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:14.29136Z","caller":"traceutil/trace.go:171","msg":"trace[1380426933] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"210.946822ms","start":"2026-04-30T05:56:14.080392Z","end":"2026-04-30T05:56:14.291339Z","steps":["trace[1380426933] 'read index received' (duration: 210.743798ms)","trace[1380426933] 'applied index is now lower than readState.Index' (duration: 202.054µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:14.291405Z","caller":"traceutil/trace.go:171","msg":"trace[338494367] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"220.137618ms","start":"2026-04-30T05:56:14.071241Z","end":"2026-04-30T05:56:14.291378Z","steps":["trace[338494367] 'process raft request' (duration: 219.921704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:14.291524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.106047ms","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":"warn","ts":"2026-04-30T05:56:14.291586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.089875ms","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":"info","ts":"2026-04-30T05:56:14.291657Z","caller":"traceutil/trace.go:171","msg":"trace[904594133] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:737; }","duration":"211.215048ms","start":"2026-04-30T05:56:14.080425Z","end":"2026-04-30T05:56:14.29164Z","steps":["trace[904594133] 'agreement among raft nodes before linearized reading' (duration: 211.082955ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:14.291614Z","caller":"traceutil/trace.go:171","msg":"trace[223756955] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:737; }","duration":"211.226489ms","start":"2026-04-30T05:56:14.08036Z","end":"2026-04-30T05:56:14.291586Z","steps":["trace[223756955] 'agreement among raft nodes before linearized reading' (duration: 211.106536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:14.291815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.8454ms","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":"info","ts":"2026-04-30T05:56:14.291855Z","caller":"traceutil/trace.go:171","msg":"trace[40914519] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:737; }","duration":"147.969113ms","start":"2026-04-30T05:56:14.143875Z","end":"2026-04-30T05:56:14.291844Z","steps":["trace[40914519] 'agreement among raft nodes before linearized reading' (duration: 147.8591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:14.291928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.503819ms","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":"warn","ts":"2026-04-30T05:56:14.291941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.900892ms","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-30T05:56:14.291969Z","caller":"traceutil/trace.go:171","msg":"trace[1895725738] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:737; }","duration":"179.580191ms","start":"2026-04-30T05:56:14.112378Z","end":"2026-04-30T05:56:14.291958Z","steps":["trace[1895725738] 'agreement among raft nodes before linearized reading' (duration: 179.51888ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:14.291979Z","caller":"traceutil/trace.go:171","msg":"trace[1279886846] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:737; }","duration":"147.967263ms","start":"2026-04-30T05:56:14.144001Z","end":"2026-04-30T05:56:14.291968Z","steps":["trace[1279886846] 'agreement among raft nodes before linearized reading' (duration: 147.882341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:14.291982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.327615ms","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":"warn","ts":"2026-04-30T05:56:14.292124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.27667ms","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-30T05:56:14.292196Z","caller":"traceutil/trace.go:171","msg":"trace[1479716770] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:737; }","duration":"179.584681ms","start":"2026-04-30T05:56:14.112505Z","end":"2026-04-30T05:56:14.29209Z","steps":["trace[1479716770] 'agreement among raft nodes before linearized reading' (duration: 179.279763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:14.292177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.648279ms","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":"warn","ts":"2026-04-30T05:56:14.292061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.097756ms","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-30T05:56:14.292308Z","caller":"traceutil/trace.go:171","msg":"trace[1058112971] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:737; }","duration":"178.829554ms","start":"2026-04-30T05:56:14.11346Z","end":"2026-04-30T05:56:14.292289Z","steps":["trace[1058112971] 'agreement among raft nodes before linearized reading' (duration: 178.618799ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:14.292377Z","caller":"traceutil/trace.go:171","msg":"trace[973007009] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:737; }","duration":"148.432353ms","start":"2026-04-30T05:56:14.143931Z","end":"2026-04-30T05:56:14.292364Z","steps":["trace[973007009] 'agreement among raft nodes before linearized reading' (duration: 148.105116ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:14.29238Z","caller":"traceutil/trace.go:171","msg":"trace[751446826] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:737; }","duration":"211.328551ms","start":"2026-04-30T05:56:14.080823Z","end":"2026-04-30T05:56:14.292152Z","steps":["trace[751446826] 'agreement among raft nodes before linearized reading' (duration: 211.27645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:14.865374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.099973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T05:56:14.865484Z","caller":"traceutil/trace.go:171","msg":"trace[948323175] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"385.627593ms","start":"2026-04-30T05:56:14.479838Z","end":"2026-04-30T05:56:14.865466Z","steps":["trace[948323175] 'read index received' (duration: 259.286314ms)","trace[948323175] 'applied index is now lower than readState.Index' (duration: 126.340069ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:14.865579Z","caller":"traceutil/trace.go:171","msg":"trace[2068470816] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"560.420996ms","start":"2026-04-30T05:56:14.305148Z","end":"2026-04-30T05:56:14.865569Z","steps":["trace[2068470816] 'process raft request' (duration: 434.026157ms)","trace[2068470816] 'compare' (duration: 125.954959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:14.865645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:14.305134Z","time spent":"560.479547ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:56:14.865835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.931579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:14.865924Z","caller":"traceutil/trace.go:171","msg":"trace[420032368] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:738; }","duration":"386.039612ms","start":"2026-04-30T05:56:14.479864Z","end":"2026-04-30T05:56:14.865903Z","steps":["trace[420032368] 'agreement among raft nodes before linearized reading' (duration: 385.892088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:14.865975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:14.479861Z","time spent":"386.102783ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-30T05:56:14.866068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.840619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T05:56:14.866134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.93284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:14.866169Z","caller":"traceutil/trace.go:171","msg":"trace[98779732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"374.174707ms","start":"2026-04-30T05:56:14.491959Z","end":"2026-04-30T05:56:14.866134Z","steps":["trace[98779732] 'agreement among raft nodes before linearized reading' (duration: 373.833399ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:14.866209Z","caller":"traceutil/trace.go:171","msg":"trace[1797290494] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:738; }","duration":"386.050292ms","start":"2026-04-30T05:56:14.480139Z","end":"2026-04-30T05:56:14.86619Z","steps":["trace[1797290494] 'agreement among raft nodes before linearized reading' (duration: 385.822757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:14.866212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:14.491946Z","time spent":"374.25706ms","remote":"127.0.0.1:53120","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-30T05:56:14.866258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:14.480136Z","time spent":"386.112194ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-04-30T05:56:14.866345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.40327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:14.866576Z","caller":"traceutil/trace.go:171","msg":"trace[384248931] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:738; }","duration":"386.645026ms","start":"2026-04-30T05:56:14.479805Z","end":"2026-04-30T05:56:14.86645Z","steps":["trace[384248931] 'agreement among raft nodes before linearized reading' (duration: 386.306129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:14.866673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:14.479784Z","time spent":"386.8596ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"info","ts":"2026-04-30T05:56:15.270576Z","caller":"traceutil/trace.go:171","msg":"trace[1715131885] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"217.217369ms","start":"2026-04-30T05:56:15.053331Z","end":"2026-04-30T05:56:15.270549Z","steps":["trace[1715131885] 'process raft request' (duration: 217.109786ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:15.270585Z","caller":"traceutil/trace.go:171","msg":"trace[1367479317] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"223.305284ms","start":"2026-04-30T05:56:15.04724Z","end":"2026-04-30T05:56:15.270545Z","steps":["trace[1367479317] 'process raft request' (duration: 162.584276ms)","trace[1367479317] 'compare' (duration: 60.37083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:15.746481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.810252ms","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-30T05:56:15.746577Z","caller":"traceutil/trace.go:171","msg":"trace[77335705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:744; }","duration":"126.942097ms","start":"2026-04-30T05:56:15.619616Z","end":"2026-04-30T05:56:15.746558Z","steps":["trace[77335705] 'range keys from in-memory index tree' (duration: 126.651549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:15.746559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.157502ms","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-30T05:56:15.746631Z","caller":"traceutil/trace.go:171","msg":"trace[755877197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:744; }","duration":"128.286636ms","start":"2026-04-30T05:56:15.618327Z","end":"2026-04-30T05:56:15.746614Z","steps":["trace[755877197] 'range keys from in-memory index tree' (duration: 127.957209ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:15.924504Z","caller":"traceutil/trace.go:171","msg":"trace[904598992] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"173.488519ms","start":"2026-04-30T05:56:15.750984Z","end":"2026-04-30T05:56:15.924472Z","steps":["trace[904598992] 'process raft request' (duration: 113.071157ms)","trace[904598992] 'compare' (duration: 60.013492ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:16.325323Z","caller":"traceutil/trace.go:171","msg":"trace[403465621] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"335.655173ms","start":"2026-04-30T05:56:15.989631Z","end":"2026-04-30T05:56:16.325286Z","steps":["trace[403465621] 'process raft request' (duration: 283.826383ms)","trace[403465621] 'compare' (duration: 51.348939ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:16.32548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:15.989619Z","time spent":"335.791435ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1478,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T05:56:16.352419Z","caller":"traceutil/trace.go:171","msg":"trace[602148874] transaction","detail":"{read_only:false; number_of_response:0; response_revision:747; }","duration":"362.382781ms","start":"2026-04-30T05:56:15.990023Z","end":"2026-04-30T05:56:16.352406Z","steps":["trace[602148874] 'process raft request' (duration: 362.266028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:16.352526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:15.990008Z","time spent":"362.457802ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T05:56:16.352643Z","caller":"traceutil/trace.go:171","msg":"trace[837069064] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"353.101754ms","start":"2026-04-30T05:56:15.999451Z","end":"2026-04-30T05:56:16.352553Z","steps":["trace[837069064] 'process raft request' (duration: 352.890559ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:16.352657Z","caller":"traceutil/trace.go:171","msg":"trace[38518865] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"281.688823ms","start":"2026-04-30T05:56:16.070787Z","end":"2026-04-30T05:56:16.352476Z","steps":["trace[38518865] 'process raft request' (duration: 281.652222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:16.352722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:15.999442Z","time spent":"353.230817ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:16.639757Z","caller":"traceutil/trace.go:171","msg":"trace[887044574] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"305.761083ms","start":"2026-04-30T05:56:16.333961Z","end":"2026-04-30T05:56:16.639722Z","steps":["trace[887044574] 'process raft request' (duration: 303.286609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:16.639981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:16.333933Z","time spent":"305.933527ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1697,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:16.681728Z","caller":"traceutil/trace.go:171","msg":"trace[1692338242] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:776; }","duration":"328.100304ms","start":"2026-04-30T05:56:16.353602Z","end":"2026-04-30T05:56:16.681702Z","steps":["trace[1692338242] 'read index received' (duration: 283.485414ms)","trace[1692338242] 'applied index is now lower than readState.Index' (duration: 44.6128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:16.681956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.313409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1712"} {"level":"info","ts":"2026-04-30T05:56:16.68207Z","caller":"traceutil/trace.go:171","msg":"trace[1192610289] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:750; }","duration":"328.767669ms","start":"2026-04-30T05:56:16.353279Z","end":"2026-04-30T05:56:16.682046Z","steps":["trace[1192610289] 'agreement among raft nodes before linearized reading' (duration: 328.554724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:16.682066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.251692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T05:56:16.682129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:16.353263Z","time spent":"328.856171ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1735,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"info","ts":"2026-04-30T05:56:16.682139Z","caller":"traceutil/trace.go:171","msg":"trace[595261902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"189.399865ms","start":"2026-04-30T05:56:16.492727Z","end":"2026-04-30T05:56:16.682127Z","steps":["trace[595261902] 'agreement among raft nodes before linearized reading' (duration: 189.251562ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:18.514077Z","caller":"traceutil/trace.go:171","msg":"trace[779445151] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"313.508141ms","start":"2026-04-30T05:56:18.200537Z","end":"2026-04-30T05:56:18.514045Z","steps":["trace[779445151] 'process raft request' (duration: 247.658479ms)","trace[779445151] 'compare' (duration: 65.671179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:18.51426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:18.200521Z","time spent":"313.624854ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:18.726927Z","caller":"traceutil/trace.go:171","msg":"trace[1535834546] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"198.910076ms","start":"2026-04-30T05:56:18.527993Z","end":"2026-04-30T05:56:18.726903Z","steps":["trace[1535834546] 'process raft request' (duration: 143.878387ms)","trace[1535834546] 'compare' (duration: 54.925486ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:18.734533Z","caller":"traceutil/trace.go:171","msg":"trace[2082917907] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"204.746472ms","start":"2026-04-30T05:56:18.529764Z","end":"2026-04-30T05:56:18.73451Z","steps":["trace[2082917907] 'process raft request' (duration: 204.615089ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:18.846403Z","caller":"traceutil/trace.go:171","msg":"trace[708157392] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"114.708237ms","start":"2026-04-30T05:56:18.731657Z","end":"2026-04-30T05:56:18.846365Z","steps":["trace[708157392] 'process raft request' (duration: 102.80416ms)","trace[708157392] 'compare' (duration: 11.615571ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:18.846705Z","caller":"traceutil/trace.go:171","msg":"trace[189243376] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"113.746898ms","start":"2026-04-30T05:56:18.732908Z","end":"2026-04-30T05:56:18.846655Z","steps":["trace[189243376] 'process raft request' (duration: 113.384209ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:18.846983Z","caller":"traceutil/trace.go:171","msg":"trace[2021280102] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"113.855769ms","start":"2026-04-30T05:56:18.733078Z","end":"2026-04-30T05:56:18.846934Z","steps":["trace[2021280102] 'process raft request' (duration: 113.473472ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:19.050346Z","caller":"traceutil/trace.go:171","msg":"trace[1226917611] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"125.787578ms","start":"2026-04-30T05:56:18.924532Z","end":"2026-04-30T05:56:19.05032Z","steps":["trace[1226917611] 'process raft request' (duration: 125.668175ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:19.050569Z","caller":"traceutil/trace.go:171","msg":"trace[399499099] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"157.575904ms","start":"2026-04-30T05:56:18.892979Z","end":"2026-04-30T05:56:19.050555Z","steps":["trace[399499099] 'process raft request' (duration: 157.112604ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:19.275996Z","caller":"traceutil/trace.go:171","msg":"trace[80248806] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"137.016604ms","start":"2026-04-30T05:56:19.138957Z","end":"2026-04-30T05:56:19.275974Z","steps":["trace[80248806] 'read index received' (duration: 77.784466ms)","trace[80248806] 'applied index is now lower than readState.Index' (duration: 59.231208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:19.276228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.252968ms","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-30T05:56:19.276387Z","caller":"traceutil/trace.go:171","msg":"trace[1031566989] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:769; }","duration":"137.471984ms","start":"2026-04-30T05:56:19.138891Z","end":"2026-04-30T05:56:19.276363Z","steps":["trace[1031566989] 'agreement among raft nodes before linearized reading' (duration: 137.235258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:19.276414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.185087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"info","ts":"2026-04-30T05:56:19.276493Z","caller":"traceutil/trace.go:171","msg":"trace[1554231803] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:769; }","duration":"137.31182ms","start":"2026-04-30T05:56:19.139161Z","end":"2026-04-30T05:56:19.276473Z","steps":["trace[1554231803] 'agreement among raft nodes before linearized reading' (duration: 137.106405ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:19.276702Z","caller":"traceutil/trace.go:171","msg":"trace[1117351356] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"218.906077ms","start":"2026-04-30T05:56:19.05778Z","end":"2026-04-30T05:56:19.276686Z","steps":["trace[1117351356] 'process raft request' (duration: 158.913723ms)","trace[1117351356] 'compare' (duration: 59.189717ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:19.416882Z","caller":"traceutil/trace.go:171","msg":"trace[1003635409] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"110.70861ms","start":"2026-04-30T05:56:19.306152Z","end":"2026-04-30T05:56:19.41686Z","steps":["trace[1003635409] 'read index received' (duration: 70.846176ms)","trace[1003635409] 'applied index is now lower than readState.Index' (duration: 39.861434ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:19.416899Z","caller":"traceutil/trace.go:171","msg":"trace[1507564584] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"132.526417ms","start":"2026-04-30T05:56:19.284344Z","end":"2026-04-30T05:56:19.41687Z","steps":["trace[1507564584] 'process raft request' (duration: 92.712734ms)","trace[1507564584] 'compare' (duration: 39.67664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:19.417172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.979255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.103\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-30T05:56:19.417213Z","caller":"traceutil/trace.go:171","msg":"trace[698842238] range","detail":"{range_begin:/registry/masterleases/199.19.213.103; range_end:; response_count:1; response_revision:770; }","duration":"111.095898ms","start":"2026-04-30T05:56:19.306107Z","end":"2026-04-30T05:56:19.417203Z","steps":["trace[698842238] 'agreement among raft nodes before linearized reading' (duration: 110.881734ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:23.949065Z","caller":"traceutil/trace.go:171","msg":"trace[194302831] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"477.238198ms","start":"2026-04-30T05:56:23.471802Z","end":"2026-04-30T05:56:23.94904Z","steps":["trace[194302831] 'read index received' (duration: 477.021424ms)","trace[194302831] 'applied index is now lower than readState.Index' (duration: 215.524µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:23.949107Z","caller":"traceutil/trace.go:171","msg":"trace[1513828241] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"481.600338ms","start":"2026-04-30T05:56:23.467486Z","end":"2026-04-30T05:56:23.949086Z","steps":["trace[1513828241] 'process raft request' (duration: 481.388384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:23.949282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:23.467468Z","time spent":"481.749472ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:56:23.949383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.551285ms","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-30T05:56:23.949471Z","caller":"traceutil/trace.go:171","msg":"trace[882254643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"477.674528ms","start":"2026-04-30T05:56:23.471772Z","end":"2026-04-30T05:56:23.949447Z","steps":["trace[882254643] 'agreement among raft nodes before linearized reading' (duration: 477.410802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:23.949519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:23.471763Z","time spent":"477.748069ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-30T05:56:24.264481Z","caller":"traceutil/trace.go:171","msg":"trace[631875084] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"315.317785ms","start":"2026-04-30T05:56:23.949141Z","end":"2026-04-30T05:56:24.264459Z","steps":["trace[631875084] 'read index received' (duration: 315.182882ms)","trace[631875084] 'applied index is now lower than readState.Index' (duration: 134.253µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:24.264607Z","caller":"traceutil/trace.go:171","msg":"trace[1335932631] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"371.346494ms","start":"2026-04-30T05:56:23.893238Z","end":"2026-04-30T05:56:24.264584Z","steps":["trace[1335932631] 'process raft request' (duration: 371.089348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:24.26463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.068499ms","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-30T05:56:24.264677Z","caller":"traceutil/trace.go:171","msg":"trace[1218680780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"388.173821ms","start":"2026-04-30T05:56:23.876492Z","end":"2026-04-30T05:56:24.264665Z","steps":["trace[1218680780] 'agreement among raft nodes before linearized reading' (duration: 388.011578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:24.264711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:23.876468Z","time spent":"388.234473ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-30T05:56:24.264727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:23.893219Z","time spent":"371.446005ms","remote":"127.0.0.1:53308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:56:24.264741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.012568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:24.264841Z","caller":"traceutil/trace.go:171","msg":"trace[1889807620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"772.158692ms","start":"2026-04-30T05:56:23.492632Z","end":"2026-04-30T05:56:24.26479Z","steps":["trace[1889807620] 'agreement among raft nodes before linearized reading' (duration: 771.997948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:24.264825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.551397ms","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-30T05:56:24.26494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:23.492621Z","time spent":"772.267944ms","remote":"127.0.0.1:53120","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-30T05:56:24.264899Z","caller":"traceutil/trace.go:171","msg":"trace[1624528253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"651.64565ms","start":"2026-04-30T05:56:23.613243Z","end":"2026-04-30T05:56:24.264889Z","steps":["trace[1624528253] 'agreement among raft nodes before linearized reading' (duration: 651.318973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:24.265178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:23.613232Z","time spent":"651.936126ms","remote":"127.0.0.1:53368","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-30T05:56:24.266926Z","caller":"traceutil/trace.go:171","msg":"trace[1153114245] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"312.866774ms","start":"2026-04-30T05:56:23.954039Z","end":"2026-04-30T05:56:24.266906Z","steps":["trace[1153114245] 'process raft request' (duration: 312.693361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:24.266963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.762852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-30T05:56:24.267022Z","caller":"traceutil/trace.go:171","msg":"trace[508098867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"232.860424ms","start":"2026-04-30T05:56:24.034146Z","end":"2026-04-30T05:56:24.267007Z","steps":["trace[508098867] 'agreement among raft nodes before linearized reading' (duration: 232.689001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:24.267061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:23.954014Z","time spent":"312.975276ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:56:28.461956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932999014315733,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T05:56:28.963149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932999014315733,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T05:56:29.435869Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.093457851s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T05:56:29.437923Z","caller":"traceutil/trace.go:171","msg":"trace[1442464062] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"1.476851571s","start":"2026-04-30T05:56:27.961037Z","end":"2026-04-30T05:56:29.437889Z","steps":["trace[1442464062] 'read index received' (duration: 1.476542296s)","trace[1442464062] 'applied index is now lower than readState.Index' (duration: 307.535µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:56:29.43809Z","caller":"traceutil/trace.go:171","msg":"trace[755335371] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"2.095839437s","start":"2026-04-30T05:56:27.342099Z","end":"2026-04-30T05:56:29.437939Z","steps":["trace[755335371] 'process raft request' (duration: 2.095522071s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.438279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:27.342081Z","time spent":"2.096086333s","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:56:29.438341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477275599s","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-30T05:56:29.438414Z","caller":"traceutil/trace.go:171","msg":"trace[1089556307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:817; }","duration":"1.477403862s","start":"2026-04-30T05:56:27.96099Z","end":"2026-04-30T05:56:29.438394Z","steps":["trace[1089556307] 'agreement among raft nodes before linearized reading' (duration: 1.477024585s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.43846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:27.96097Z","time spent":"1.477477403s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-30T05:56:29.458136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.633776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:29.45818Z","caller":"traceutil/trace.go:171","msg":"trace[1371944779] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:818; }","duration":"414.724267ms","start":"2026-04-30T05:56:29.043445Z","end":"2026-04-30T05:56:29.458169Z","steps":["trace[1371944779] 'agreement among raft nodes before linearized reading' (duration: 414.650915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.458205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:29.043423Z","time spent":"414.775348ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-30T05:56:29.458199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177624948s","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-30T05:56:29.458255Z","caller":"traceutil/trace.go:171","msg":"trace[413171191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:818; }","duration":"1.17772191s","start":"2026-04-30T05:56:28.280516Z","end":"2026-04-30T05:56:29.458238Z","steps":["trace[413171191] 'agreement among raft nodes before linearized reading' (duration: 1.177536736s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.4583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:28.280494Z","time spent":"1.177798322s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T05:56:29.458414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.353266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.103\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-30T05:56:29.45844Z","caller":"traceutil/trace.go:171","msg":"trace[941630238] range","detail":"{range_begin:/registry/masterleases/199.19.213.103; range_end:; response_count:1; response_revision:818; }","duration":"151.429578ms","start":"2026-04-30T05:56:29.307005Z","end":"2026-04-30T05:56:29.458434Z","steps":["trace[941630238] 'agreement among raft nodes before linearized reading' (duration: 151.335876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.458502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.683675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:29.458539Z","caller":"traceutil/trace.go:171","msg":"trace[1350456291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"964.734507ms","start":"2026-04-30T05:56:28.493795Z","end":"2026-04-30T05:56:29.458529Z","steps":["trace[1350456291] 'agreement among raft nodes before linearized reading' (duration: 964.678956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.458567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:28.493786Z","time spent":"964.775817ms","remote":"127.0.0.1:53120","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-30T05:56:29.458177Z","caller":"traceutil/trace.go:171","msg":"trace[305476681] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"322.275169ms","start":"2026-04-30T05:56:29.135865Z","end":"2026-04-30T05:56:29.45814Z","steps":["trace[305476681] 'process raft request' (duration: 322.062184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.458744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:29.135836Z","time spent":"322.85594ms","remote":"127.0.0.1:53368","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-30T05:56:29.45899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16514229s","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-30T05:56:29.459018Z","caller":"traceutil/trace.go:171","msg":"trace[1909543521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"1.165194131s","start":"2026-04-30T05:56:28.293817Z","end":"2026-04-30T05:56:29.459011Z","steps":["trace[1909543521] 'agreement among raft nodes before linearized reading' (duration: 1.165122929s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.459043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:28.293806Z","time spent":"1.165231211s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-30T05:56:29.459146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165306633s","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-30T05:56:29.459196Z","caller":"traceutil/trace.go:171","msg":"trace[1447331121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"1.165378024s","start":"2026-04-30T05:56:28.293807Z","end":"2026-04-30T05:56:29.459185Z","steps":["trace[1447331121] 'agreement among raft nodes before linearized reading' (duration: 1.165251282s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.459233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:28.293797Z","time spent":"1.165429355s","remote":"127.0.0.1:53368","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-30T05:56:29.459641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166977096s","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-30T05:56:29.459684Z","caller":"traceutil/trace.go:171","msg":"trace[1566996282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:818; }","duration":"1.167663049s","start":"2026-04-30T05:56:28.29201Z","end":"2026-04-30T05:56:29.459673Z","steps":["trace[1566996282] 'agreement among raft nodes before linearized reading' (duration: 1.166569378s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:29.459732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:28.291997Z","time spent":"1.167706511s","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-30T05:56:33.198705Z","caller":"traceutil/trace.go:171","msg":"trace[1696100978] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"114.97539ms","start":"2026-04-30T05:56:33.083709Z","end":"2026-04-30T05:56:33.198684Z","steps":["trace[1696100978] 'process raft request' (duration: 114.829197ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:36.034291Z","caller":"traceutil/trace.go:171","msg":"trace[1236963709] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"437.235412ms","start":"2026-04-30T05:56:35.597038Z","end":"2026-04-30T05:56:36.034273Z","steps":["trace[1236963709] 'process raft request' (duration: 381.879929ms)","trace[1236963709] 'compare' (duration: 55.240981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:36.034408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:35.59702Z","time spent":"437.332123ms","remote":"127.0.0.1:53368","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-30T05:56:36.035185Z","caller":"traceutil/trace.go:171","msg":"trace[2122962071] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"437.695351ms","start":"2026-04-30T05:56:35.597467Z","end":"2026-04-30T05:56:36.035162Z","steps":["trace[2122962071] 'process raft request' (duration: 436.782654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:36.035446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:35.597456Z","time spent":"437.799754ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:56:36.613664Z","caller":"traceutil/trace.go:171","msg":"trace[1772753580] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"121.615012ms","start":"2026-04-30T05:56:36.492026Z","end":"2026-04-30T05:56:36.613641Z","steps":["trace[1772753580] 'read index received' (duration: 119.385342ms)","trace[1772753580] 'applied index is now lower than readState.Index' (duration: 2.22869ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:56:36.61379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.750485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:56:36.613828Z","caller":"traceutil/trace.go:171","msg":"trace[2016506353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"121.821036ms","start":"2026-04-30T05:56:36.491993Z","end":"2026-04-30T05:56:36.613814Z","steps":["trace[2016506353] 'agreement among raft nodes before linearized reading' (duration: 121.744704ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:36.613799Z","caller":"traceutil/trace.go:171","msg":"trace[132792414] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"271.783176ms","start":"2026-04-30T05:56:36.341986Z","end":"2026-04-30T05:56:36.613769Z","steps":["trace[132792414] 'process raft request' (duration: 269.545585ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:36.800344Z","caller":"traceutil/trace.go:171","msg":"trace[640814708] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"178.519861ms","start":"2026-04-30T05:56:36.621807Z","end":"2026-04-30T05:56:36.800327Z","steps":["trace[640814708] 'process raft request' (duration: 178.416019ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:36.82157Z","caller":"traceutil/trace.go:171","msg":"trace[1249473037] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"199.649084ms","start":"2026-04-30T05:56:36.621896Z","end":"2026-04-30T05:56:36.821545Z","steps":["trace[1249473037] 'process raft request' (duration: 199.44606ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:36.822005Z","caller":"traceutil/trace.go:171","msg":"trace[1207426022] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"199.089473ms","start":"2026-04-30T05:56:36.622904Z","end":"2026-04-30T05:56:36.821993Z","steps":["trace[1207426022] 'process raft request' (duration: 198.586324ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:37.083109Z","caller":"traceutil/trace.go:171","msg":"trace[894887172] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"255.136974ms","start":"2026-04-30T05:56:36.827955Z","end":"2026-04-30T05:56:37.083092Z","steps":["trace[894887172] 'process raft request' (duration: 254.403591ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:56:40.781371Z","caller":"traceutil/trace.go:171","msg":"trace[1042594896] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"135.095478ms","start":"2026-04-30T05:56:40.646252Z","end":"2026-04-30T05:56:40.781347Z","steps":["trace[1042594896] 'process raft request' (duration: 134.941205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:43.955701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.648866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-t297h\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-04-30T05:56:43.955791Z","caller":"traceutil/trace.go:171","msg":"trace[1602472156] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-t297h; range_end:; response_count:1; response_revision:976; }","duration":"338.787168ms","start":"2026-04-30T05:56:43.616983Z","end":"2026-04-30T05:56:43.955771Z","steps":["trace[1602472156] 'range keys from in-memory index tree' (duration: 338.396332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:43.955836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:43.616971Z","time spent":"338.85543ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5700,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-t297h\" "} {"level":"warn","ts":"2026-04-30T05:56:43.955892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.430626ms","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-30T05:56:43.955965Z","caller":"traceutil/trace.go:171","msg":"trace[234081111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"332.545468ms","start":"2026-04-30T05:56:43.623402Z","end":"2026-04-30T05:56:43.955948Z","steps":["trace[234081111] 'range keys from in-memory index tree' (duration: 332.269083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:43.956004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:43.623383Z","time spent":"332.612379ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-30T05:56:43.956082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.179883ms","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-30T05:56:43.956305Z","caller":"traceutil/trace.go:171","msg":"trace[933029864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"255.749853ms","start":"2026-04-30T05:56:43.700524Z","end":"2026-04-30T05:56:43.956274Z","steps":["trace[933029864] 'range keys from in-memory index tree' (duration: 255.022821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:43.956162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.744782ms","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-30T05:56:43.956644Z","caller":"traceutil/trace.go:171","msg":"trace[724384250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"367.255792ms","start":"2026-04-30T05:56:43.589373Z","end":"2026-04-30T05:56:43.956629Z","steps":["trace[724384250] 'range keys from in-memory index tree' (duration: 366.63065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:56:43.956692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:56:43.589357Z","time spent":"367.322113ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-30T05:56:46.454526Z","caller":"traceutil/trace.go:171","msg":"trace[651735528] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"134.87327ms","start":"2026-04-30T05:56:46.319617Z","end":"2026-04-30T05:56:46.45449Z","steps":["trace[651735528] 'process raft request' (duration: 134.600365ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:57:06.219876Z","caller":"traceutil/trace.go:171","msg":"trace[577430792] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"131.519953ms","start":"2026-04-30T05:57:06.088324Z","end":"2026-04-30T05:57:06.219844Z","steps":["trace[577430792] 'read index received' (duration: 131.268769ms)","trace[577430792] 'applied index is now lower than readState.Index' (duration: 249.514µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:57:06.22001Z","caller":"traceutil/trace.go:171","msg":"trace[2087289411] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"146.713294ms","start":"2026-04-30T05:57:06.07324Z","end":"2026-04-30T05:57:06.219953Z","steps":["trace[2087289411] 'process raft request' (duration: 146.379079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:57:06.220165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.819448ms","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-30T05:57:06.220188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.756747ms","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-30T05:57:06.220214Z","caller":"traceutil/trace.go:171","msg":"trace[652040669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"131.908291ms","start":"2026-04-30T05:57:06.088293Z","end":"2026-04-30T05:57:06.220201Z","steps":["trace[652040669] 'agreement among raft nodes before linearized reading' (duration: 131.730858ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:57:06.220238Z","caller":"traceutil/trace.go:171","msg":"trace[213036715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"131.856308ms","start":"2026-04-30T05:57:06.088368Z","end":"2026-04-30T05:57:06.220225Z","steps":["trace[213036715] 'agreement among raft nodes before linearized reading' (duration: 131.702356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:57:24.637601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.685514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T05:57:24.637859Z","caller":"traceutil/trace.go:171","msg":"trace[1074877740] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"256.383466ms","start":"2026-04-30T05:57:24.381442Z","end":"2026-04-30T05:57:24.637825Z","steps":["trace[1074877740] 'process raft request' (duration: 54.378168ms)","trace[1074877740] 'compare' (duration: 201.522542ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:57:24.637915Z","caller":"traceutil/trace.go:171","msg":"trace[1092826334] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1170; }","duration":"237.303143ms","start":"2026-04-30T05:57:24.400586Z","end":"2026-04-30T05:57:24.637889Z","steps":["trace[1092826334] 'read index received' (duration: 35.177873ms)","trace[1092826334] 'applied index is now lower than readState.Index' (duration: 202.12264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:57:24.695538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.77655ms","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-30T05:57:24.695662Z","caller":"traceutil/trace.go:171","msg":"trace[678427010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1129; }","duration":"293.907262ms","start":"2026-04-30T05:57:24.401742Z","end":"2026-04-30T05:57:24.69565Z","steps":["trace[678427010] 'agreement among raft nodes before linearized reading' (duration: 293.71855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:57:24.695706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.844639ms","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-30T05:57:24.695848Z","caller":"traceutil/trace.go:171","msg":"trace[1606071953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1129; }","duration":"288.00679ms","start":"2026-04-30T05:57:24.407823Z","end":"2026-04-30T05:57:24.695829Z","steps":["trace[1606071953] 'agreement among raft nodes before linearized reading' (duration: 287.788478ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:57:24.695549Z","caller":"traceutil/trace.go:171","msg":"trace[400692191] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"121.011954ms","start":"2026-04-30T05:57:24.574506Z","end":"2026-04-30T05:57:24.695518Z","steps":["trace[400692191] 'process raft request' (duration: 120.848652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:57:24.695567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.533375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:57:24.696388Z","caller":"traceutil/trace.go:171","msg":"trace[1426863992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1129; }","duration":"203.316657ms","start":"2026-04-30T05:57:24.492988Z","end":"2026-04-30T05:57:24.696304Z","steps":["trace[1426863992] 'agreement among raft nodes before linearized reading' (duration: 202.534205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:57:24.698008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.905055ms","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-30T05:57:24.698071Z","caller":"traceutil/trace.go:171","msg":"trace[1438466121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1128; }","duration":"297.483432ms","start":"2026-04-30T05:57:24.40056Z","end":"2026-04-30T05:57:24.698043Z","steps":["trace[1438466121] 'agreement among raft nodes before linearized reading' (duration: 237.473047ms)","trace[1438466121] 'range keys from in-memory index tree' (duration: 57.358959ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:57:59.123716Z","caller":"traceutil/trace.go:171","msg":"trace[1619088767] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"289.365689ms","start":"2026-04-30T05:57:58.834057Z","end":"2026-04-30T05:57:59.123423Z","steps":["trace[1619088767] 'process raft request' (duration: 289.107226ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:57:59.124553Z","caller":"traceutil/trace.go:171","msg":"trace[809067925] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1289; }","duration":"169.382553ms","start":"2026-04-30T05:57:58.955154Z","end":"2026-04-30T05:57:59.124537Z","steps":["trace[809067925] 'read index received' (duration: 168.058827ms)","trace[809067925] 'applied index is now lower than readState.Index' (duration: 1.322966ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:57:59.124574Z","caller":"traceutil/trace.go:171","msg":"trace[11849072] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"243.808804ms","start":"2026-04-30T05:57:58.880744Z","end":"2026-04-30T05:57:59.124553Z","steps":["trace[11849072] 'process raft request' (duration: 243.704333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:57:59.124801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.637887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-30T05:57:59.124816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.437772ms","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-30T05:57:59.12484Z","caller":"traceutil/trace.go:171","msg":"trace[152798109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"169.731197ms","start":"2026-04-30T05:57:58.955099Z","end":"2026-04-30T05:57:59.124831Z","steps":["trace[152798109] 'agreement among raft nodes before linearized reading' (duration: 169.520475ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:57:59.124871Z","caller":"traceutil/trace.go:171","msg":"trace[1285706035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"168.538983ms","start":"2026-04-30T05:57:58.956318Z","end":"2026-04-30T05:57:59.124857Z","steps":["trace[1285706035] 'agreement among raft nodes before linearized reading' (duration: 168.392641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:57:59.125042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.881744ms","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-30T05:57:59.125127Z","caller":"traceutil/trace.go:171","msg":"trace[1908436533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"168.002816ms","start":"2026-04-30T05:57:58.957104Z","end":"2026-04-30T05:57:59.125107Z","steps":["trace[1908436533] 'agreement among raft nodes before linearized reading' (duration: 167.630701ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:58:13.508521Z","caller":"traceutil/trace.go:171","msg":"trace[1697978459] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"309.603853ms","start":"2026-04-30T05:58:13.198894Z","end":"2026-04-30T05:58:13.508498Z","steps":["trace[1697978459] 'read index received' (duration: 304.799079ms)","trace[1697978459] 'applied index is now lower than readState.Index' (duration: 4.804024ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:58:13.508731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.924808ms","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-30T05:58:13.508772Z","caller":"traceutil/trace.go:171","msg":"trace[51306785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"282.988228ms","start":"2026-04-30T05:58:13.225771Z","end":"2026-04-30T05:58:13.508759Z","steps":["trace[51306785] 'agreement among raft nodes before linearized reading' (duration: 282.849137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:13.508843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.955668ms","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-30T05:58:13.508873Z","caller":"traceutil/trace.go:171","msg":"trace[1167086040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1320; }","duration":"310.020458ms","start":"2026-04-30T05:58:13.198842Z","end":"2026-04-30T05:58:13.508862Z","steps":["trace[1167086040] 'agreement among raft nodes before linearized reading' (duration: 309.943368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:13.508906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:13.198827Z","time spent":"310.072469ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-30T05:58:13.508959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.548304ms","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-30T05:58:13.508996Z","caller":"traceutil/trace.go:171","msg":"trace[1465613306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"227.626396ms","start":"2026-04-30T05:58:13.281361Z","end":"2026-04-30T05:58:13.508987Z","steps":["trace[1465613306] 'agreement among raft nodes before linearized reading' (duration: 227.540605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:13.50911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.202174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-30T05:58:13.509152Z","caller":"traceutil/trace.go:171","msg":"trace[1468902807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1320; }","duration":"250.286484ms","start":"2026-04-30T05:58:13.258856Z","end":"2026-04-30T05:58:13.509142Z","steps":["trace[1468902807] 'agreement among raft nodes before linearized reading' (duration: 250.203534ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:58:13.509526Z","caller":"traceutil/trace.go:171","msg":"trace[1671670096] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"312.151442ms","start":"2026-04-30T05:58:13.196397Z","end":"2026-04-30T05:58:13.508548Z","steps":["trace[1671670096] 'process raft request' (duration: 307.339306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:13.509783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:13.196374Z","time spent":"313.279665ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:58:16.645429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.32138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-30T05:58:16.645519Z","caller":"traceutil/trace.go:171","msg":"trace[1427816392] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1333; }","duration":"101.446441ms","start":"2026-04-30T05:58:16.544053Z","end":"2026-04-30T05:58:16.6455Z","steps":["trace[1427816392] 'range keys from in-memory index tree' (duration: 101.204128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:16.645687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.196267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:58:16.645778Z","caller":"traceutil/trace.go:171","msg":"trace[2055519341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1333; }","duration":"151.342148ms","start":"2026-04-30T05:58:16.494415Z","end":"2026-04-30T05:58:16.645757Z","steps":["trace[2055519341] 'range keys from in-memory index tree' (duration: 151.071166ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:58:16.645793Z","caller":"traceutil/trace.go:171","msg":"trace[1450369445] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1333; }","duration":"100.033075ms","start":"2026-04-30T05:58:16.545737Z","end":"2026-04-30T05:58:16.64577Z","steps":["trace[1450369445] 'range keys from in-memory index tree' (duration: 99.765972ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:58:17.089581Z","caller":"traceutil/trace.go:171","msg":"trace[1354049736] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"286.571051ms","start":"2026-04-30T05:58:16.802974Z","end":"2026-04-30T05:58:17.089545Z","steps":["trace[1354049736] 'read index received' (duration: 286.208328ms)","trace[1354049736] 'applied index is now lower than readState.Index' (duration: 360.843µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T05:58:17.089626Z","caller":"traceutil/trace.go:171","msg":"trace[2082945340] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"357.199272ms","start":"2026-04-30T05:58:16.732395Z","end":"2026-04-30T05:58:17.089594Z","steps":["trace[2082945340] 'process raft request' (duration: 356.913349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:17.089786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:16.73238Z","time spent":"357.324653ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T05:58:17.089954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.946535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-04-30T05:58:17.090003Z","caller":"traceutil/trace.go:171","msg":"trace[1437746117] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1334; }","duration":"287.064836ms","start":"2026-04-30T05:58:16.802926Z","end":"2026-04-30T05:58:17.089991Z","steps":["trace[1437746117] 'agreement among raft nodes before linearized reading' (duration: 286.818654ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:58:17.220598Z","caller":"traceutil/trace.go:171","msg":"trace[1856961677] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"125.927306ms","start":"2026-04-30T05:58:17.094632Z","end":"2026-04-30T05:58:17.22056Z","steps":["trace[1856961677] 'process raft request' (duration: 125.174197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:22.069261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396932999014317003,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-30T05:58:22.424869Z","caller":"traceutil/trace.go:171","msg":"trace[2066586318] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"874.117297ms","start":"2026-04-30T05:58:21.550725Z","end":"2026-04-30T05:58:22.424842Z","steps":["trace[2066586318] 'process raft request' (duration: 873.880175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:22.425212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:21.550686Z","time spent":"874.445791ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:58:22.452931Z","caller":"traceutil/trace.go:171","msg":"trace[546017054] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1407; }","duration":"883.749174ms","start":"2026-04-30T05:58:21.569151Z","end":"2026-04-30T05:58:22.4529Z","steps":["trace[546017054] 'read index received' (duration: 855.548889ms)","trace[546017054] 'applied index is now lower than readState.Index' (duration: 28.198955ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:58:22.453191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.185236ms","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-30T05:58:22.453234Z","caller":"traceutil/trace.go:171","msg":"trace[1762200088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1353; }","duration":"840.264896ms","start":"2026-04-30T05:58:21.612959Z","end":"2026-04-30T05:58:22.453223Z","steps":["trace[1762200088] 'agreement among raft nodes before linearized reading' (duration: 840.105255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:22.453218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.542192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-30T05:58:22.453228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.059797ms","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-30T05:58:22.453812Z","caller":"traceutil/trace.go:171","msg":"trace[15773076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1353; }","duration":"884.669993ms","start":"2026-04-30T05:58:21.569129Z","end":"2026-04-30T05:58:22.453799Z","steps":["trace[15773076] 'agreement among raft nodes before linearized reading' (duration: 883.878365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:22.453972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:21.569119Z","time spent":"884.836896ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-30T05:58:22.453265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.935778ms","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-30T05:58:22.454265Z","caller":"traceutil/trace.go:171","msg":"trace[1129214827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1353; }","duration":"836.946549ms","start":"2026-04-30T05:58:21.617305Z","end":"2026-04-30T05:58:22.454251Z","steps":["trace[1129214827] 'agreement among raft nodes before linearized reading' (duration: 835.874587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:22.454347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:21.617292Z","time spent":"837.04064ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-30T05:58:22.453277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:21.612952Z","time spent":"840.312357ms","remote":"127.0.0.1:53368","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-30T05:58:22.453278Z","caller":"traceutil/trace.go:171","msg":"trace[544789339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1353; }","duration":"834.660764ms","start":"2026-04-30T05:58:21.618602Z","end":"2026-04-30T05:58:22.453262Z","steps":["trace[544789339] 'agreement among raft nodes before linearized reading' (duration: 834.485422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:22.454878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:21.618581Z","time spent":"836.281372ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-30T05:58:22.453591Z","caller":"traceutil/trace.go:171","msg":"trace[492327451] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"901.904977ms","start":"2026-04-30T05:58:21.551657Z","end":"2026-04-30T05:58:22.453562Z","steps":["trace[492327451] 'process raft request' (duration: 901.067068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:22.45512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:21.551642Z","time spent":"903.384824ms","remote":"127.0.0.1:53368","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-30T05:58:43.069658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.963111ms","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-30T05:58:43.069749Z","caller":"traceutil/trace.go:171","msg":"trace[409688984] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1425; }","duration":"240.062702ms","start":"2026-04-30T05:58:42.829673Z","end":"2026-04-30T05:58:43.069736Z","steps":["trace[409688984] 'range keys from in-memory index tree' (duration: 239.954671ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:58:43.070446Z","caller":"traceutil/trace.go:171","msg":"trace[1824676829] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"342.371387ms","start":"2026-04-30T05:58:42.728047Z","end":"2026-04-30T05:58:43.070419Z","steps":["trace[1824676829] 'process raft request' (duration: 246.140228ms)","trace[1824676829] 'compare' (duration: 95.579203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:58:43.070606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:42.728024Z","time spent":"342.500809ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:58:43.070626Z","caller":"traceutil/trace.go:171","msg":"trace[560491220] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"341.992163ms","start":"2026-04-30T05:58:42.72861Z","end":"2026-04-30T05:58:43.070602Z","steps":["trace[560491220] 'process raft request' (duration: 341.719281ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:58:43.070582Z","caller":"traceutil/trace.go:171","msg":"trace[1820479091] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1485; }","duration":"272.291077ms","start":"2026-04-30T05:58:42.79826Z","end":"2026-04-30T05:58:43.070551Z","steps":["trace[1820479091] 'read index received' (duration: 175.879606ms)","trace[1820479091] 'applied index is now lower than readState.Index' (duration: 96.409511ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T05:58:43.070712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.418588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-node-lease/\" range_end:\"/registry/services/specs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T05:58:43.070741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T05:58:42.728603Z","time spent":"342.091594ms","remote":"127.0.0.1:53368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T05:58:43.070757Z","caller":"traceutil/trace.go:171","msg":"trace[1610206020] range","detail":"{range_begin:/registry/services/specs/kube-node-lease/; range_end:/registry/services/specs/kube-node-lease0; response_count:0; response_revision:1427; }","duration":"272.53282ms","start":"2026-04-30T05:58:42.798214Z","end":"2026-04-30T05:58:43.070747Z","steps":["trace[1610206020] 'agreement among raft nodes before linearized reading' (duration: 272.422768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:43.071131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.04735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-04-30T05:58:43.071168Z","caller":"traceutil/trace.go:171","msg":"trace[1363803084] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:1428; }","duration":"267.100141ms","start":"2026-04-30T05:58:42.804059Z","end":"2026-04-30T05:58:43.071159Z","steps":["trace[1363803084] 'agreement among raft nodes before linearized reading' (duration: 266.99065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:43.071212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.042876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" ","response":"range_response_count:1 size:4460"} {"level":"info","ts":"2026-04-30T05:58:43.071259Z","caller":"traceutil/trace.go:171","msg":"trace[1012067063] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-rate-limit; range_end:; response_count:1; response_revision:1428; }","duration":"205.121227ms","start":"2026-04-30T05:58:42.866126Z","end":"2026-04-30T05:58:43.071248Z","steps":["trace[1012067063] 'agreement among raft nodes before linearized reading' (duration: 204.975615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T05:58:43.071399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.236166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-public/\" range_end:\"/registry/jobs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T05:58:43.071427Z","caller":"traceutil/trace.go:171","msg":"trace[2101514324] range","detail":"{range_begin:/registry/jobs/kube-public/; range_end:/registry/jobs/kube-public0; response_count:0; response_revision:1428; }","duration":"259.293187ms","start":"2026-04-30T05:58:42.812127Z","end":"2026-04-30T05:58:43.07142Z","steps":["trace[2101514324] 'agreement among raft nodes before linearized reading' (duration: 259.246047ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T05:58:53.525355Z","caller":"traceutil/trace.go:171","msg":"trace[155119493] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"126.216767ms","start":"2026-04-30T05:58:53.399113Z","end":"2026-04-30T05:58:53.52533Z","steps":["trace[155119493] 'process raft request' (duration: 126.028125ms)"],"step_count":1}