{"level":"warn","ts":"2026-05-27T19:36:14.510974Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:36:14.511056Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.201: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.201:2380","--initial-cluster=instance=https://199.19.213.201:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.201:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.201: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-05-27T19:36:14.511192Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:36:14.511209Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-05-27T19:36:14.51145Z","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-05-27T19:36:14.512131Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"]} {"level":"info","ts":"2026-05-27T19:36:14.512253Z","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.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.201: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-05-27T19:36:14.522811Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.319785ms"} {"level":"info","ts":"2026-05-27T19:36:14.530847Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61bc451777564e43","cluster-id":"b1814cb801e33ddc"} {"level":"info","ts":"2026-05-27T19:36:14.531061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:36:14.531158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 0"} {"level":"info","ts":"2026-05-27T19:36:14.531184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61bc451777564e43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T19:36:14.5312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 1"} {"level":"info","ts":"2026-05-27T19:36:14.531287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"warn","ts":"2026-05-27T19:36:14.540588Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:36:14.542144Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:36:14.543429Z","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-05-27T19:36:14.545623Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61bc451777564e43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T19:36:14.545729Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61bc451777564e43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T19:36:14.546061Z","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-05-27T19:36:14.546137Z","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-05-27T19:36:14.546148Z","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-05-27T19:36:14.546636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"info","ts":"2026-05-27T19:36:14.546729Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","added-peer-id":"61bc451777564e43","added-peer-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-05-27T19:36:14.552536Z","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-05-27T19:36:14.55261Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-05-27T19:36:14.552638Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-05-27T19:36:14.552885Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61bc451777564e43","initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T19:36:14.552935Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:36:14.834264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:36:14.834305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:36:14.834329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgPreVoteResp from 61bc451777564e43 at term 1"} {"level":"info","ts":"2026-05-27T19:36:14.83434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:36:14.834345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgVoteResp from 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-05-27T19:36:14.834353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became leader at term 2"} {"level":"info","ts":"2026-05-27T19:36:14.834449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61bc451777564e43 elected leader 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-05-27T19:36:14.842626Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:36:14.847659Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:36:14.847743Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:36:14.848206Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:36:14.848288Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:36:14.849996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:36:14.847631Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61bc451777564e43","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.201:2379]}","request-path":"/0/members/61bc451777564e43/attributes","cluster-id":"b1814cb801e33ddc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T19:36:14.858759Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.201:2379"} {"level":"info","ts":"2026-05-27T19:36:14.875685Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:36:14.875824Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:36:14.875861Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:36:16.548531Z","caller":"traceutil/trace.go:171","msg":"trace[1047165215] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"196.48735ms","start":"2026-05-27T19:36:16.35202Z","end":"2026-05-27T19:36:16.548507Z","steps":["trace[1047165215] 'process raft request' (duration: 196.451839ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:16.54873Z","caller":"traceutil/trace.go:171","msg":"trace[60562160] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"196.736859ms","start":"2026-05-27T19:36:16.351974Z","end":"2026-05-27T19:36:16.548711Z","steps":["trace[60562160] 'process raft request' (duration: 196.444569ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:16.548827Z","caller":"traceutil/trace.go:171","msg":"trace[1060579167] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"198.094886ms","start":"2026-05-27T19:36:16.350685Z","end":"2026-05-27T19:36:16.548779Z","steps":["trace[1060579167] 'process raft request' (duration: 169.851829ms)","trace[1060579167] 'compare' (duration: 27.687048ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:16.548911Z","caller":"traceutil/trace.go:171","msg":"trace[1862923821] linearizableReadLoop","detail":"{readStateIndex:40; appliedIndex:33; }","duration":"131.971178ms","start":"2026-05-27T19:36:16.416928Z","end":"2026-05-27T19:36:16.548899Z","steps":["trace[1862923821] 'read index received' (duration: 103.620936ms)","trace[1862923821] 'applied index is now lower than readState.Index' (duration: 28.349051ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:16.54902Z","caller":"traceutil/trace.go:171","msg":"trace[332057485] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"133.549182ms","start":"2026-05-27T19:36:16.415461Z","end":"2026-05-27T19:36:16.549011Z","steps":["trace[332057485] 'process raft request' (duration: 133.382697ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:16.549049Z","caller":"traceutil/trace.go:171","msg":"trace[578896370] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"133.847722ms","start":"2026-05-27T19:36:16.415195Z","end":"2026-05-27T19:36:16.549042Z","steps":["trace[578896370] 'process raft request' (duration: 133.570573ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:16.549075Z","caller":"traceutil/trace.go:171","msg":"trace[1910224072] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"133.763919ms","start":"2026-05-27T19:36:16.415306Z","end":"2026-05-27T19:36:16.54907Z","steps":["trace[1910224072] 'process raft request' (duration: 133.507801ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:16.549112Z","caller":"traceutil/trace.go:171","msg":"trace[1517172958] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"133.056335ms","start":"2026-05-27T19:36:16.416049Z","end":"2026-05-27T19:36:16.549105Z","steps":["trace[1517172958] 'process raft request' (duration: 132.818636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:16.549226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.281748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:36:16.550474Z","caller":"traceutil/trace.go:171","msg":"trace[1735539629] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:36; }","duration":"133.535041ms","start":"2026-05-27T19:36:16.416913Z","end":"2026-05-27T19:36:16.550448Z","steps":["trace[1735539629] 'agreement among raft nodes before linearized reading' (duration: 132.019639ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:18.301835Z","caller":"traceutil/trace.go:171","msg":"trace[1337207835] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"148.044406ms","start":"2026-05-27T19:36:18.153751Z","end":"2026-05-27T19:36:18.301795Z","steps":["trace[1337207835] 'process raft request' (duration: 147.803457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:19.720643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.791237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-05-27T19:36:19.720781Z","caller":"traceutil/trace.go:171","msg":"trace[146042830] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:205; }","duration":"115.002075ms","start":"2026-05-27T19:36:19.605751Z","end":"2026-05-27T19:36:19.720753Z","steps":["trace[146042830] 'range keys from in-memory index tree' (duration: 114.57742ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:19.902815Z","caller":"traceutil/trace.go:171","msg":"trace[1413087923] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"111.093124ms","start":"2026-05-27T19:36:19.791647Z","end":"2026-05-27T19:36:19.90274Z","steps":["trace[1413087923] 'process raft request' (duration: 110.824755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:20.253033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.94545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:36:20.253185Z","caller":"traceutil/trace.go:171","msg":"trace[1183272033] linearizableReadLoop","detail":"{readStateIndex:219; appliedIndex:218; }","duration":"262.46794ms","start":"2026-05-27T19:36:19.990658Z","end":"2026-05-27T19:36:20.253126Z","steps":["trace[1183272033] 'read index received' (duration: 49.789906ms)","trace[1183272033] 'applied index is now lower than readState.Index' (duration: 212.676824ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:20.253232Z","caller":"traceutil/trace.go:171","msg":"trace[1070801963] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"278.033543ms","start":"2026-05-27T19:36:19.97514Z","end":"2026-05-27T19:36:20.253173Z","steps":["trace[1070801963] 'process raft request' (duration: 65.287347ms)","trace[1070801963] 'compare' (duration: 211.791974ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:36:20.253338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.678198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-27T19:36:20.253403Z","caller":"traceutil/trace.go:171","msg":"trace[1673525023] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:213; }","duration":"262.790231ms","start":"2026-05-27T19:36:19.990601Z","end":"2026-05-27T19:36:20.253391Z","steps":["trace[1673525023] 'agreement among raft nodes before linearized reading' (duration: 262.619556ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:20.25486Z","caller":"traceutil/trace.go:171","msg":"trace[1229154442] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"262.1677ms","start":"2026-05-27T19:36:19.992677Z","end":"2026-05-27T19:36:20.254845Z","steps":["trace[1229154442] 'process raft request' (duration: 262.114569ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:20.268032Z","caller":"traceutil/trace.go:171","msg":"trace[2028994548] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"156.929915ms","start":"2026-05-27T19:36:20.111076Z","end":"2026-05-27T19:36:20.268006Z","steps":["trace[2028994548] 'process raft request' (duration: 156.709688ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:22.193749Z","caller":"traceutil/trace.go:171","msg":"trace[1305810851] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"177.483544ms","start":"2026-05-27T19:36:22.016224Z","end":"2026-05-27T19:36:22.193707Z","steps":["trace[1305810851] 'process raft request' (duration: 177.3512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:22.193853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.161007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-27T19:36:22.193983Z","caller":"traceutil/trace.go:171","msg":"trace[1797905754] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:265; }","duration":"178.298081ms","start":"2026-05-27T19:36:22.015646Z","end":"2026-05-27T19:36:22.193944Z","steps":["trace[1797905754] 'agreement among raft nodes before linearized reading' (duration: 178.070664ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:22.285414Z","caller":"traceutil/trace.go:171","msg":"trace[2058206320] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"171.101907ms","start":"2026-05-27T19:36:22.11429Z","end":"2026-05-27T19:36:22.285392Z","steps":["trace[2058206320] 'process raft request' (duration: 170.913751ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:22.285476Z","caller":"traceutil/trace.go:171","msg":"trace[721079882] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"147.639415ms","start":"2026-05-27T19:36:22.137803Z","end":"2026-05-27T19:36:22.285442Z","steps":["trace[721079882] 'process raft request' (duration: 147.507001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:22.285532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.730518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-27T19:36:22.285585Z","caller":"traceutil/trace.go:171","msg":"trace[1775473433] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:265; }","duration":"175.813201ms","start":"2026-05-27T19:36:22.109757Z","end":"2026-05-27T19:36:22.285571Z","steps":["trace[1775473433] 'agreement among raft nodes before linearized reading' (duration: 175.722048ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:22.28556Z","caller":"traceutil/trace.go:171","msg":"trace[1755544624] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"146.820308ms","start":"2026-05-27T19:36:22.138683Z","end":"2026-05-27T19:36:22.285503Z","steps":["trace[1755544624] 'process raft request' (duration: 146.751736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:22.285705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.185512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-05-27T19:36:22.285744Z","caller":"traceutil/trace.go:171","msg":"trace[372824537] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:265; }","duration":"176.223734ms","start":"2026-05-27T19:36:22.109512Z","end":"2026-05-27T19:36:22.285736Z","steps":["trace[372824537] 'agreement among raft nodes before linearized reading' (duration: 176.162442ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:22.285828Z","caller":"traceutil/trace.go:171","msg":"trace[438086136] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"147.430838ms","start":"2026-05-27T19:36:22.13837Z","end":"2026-05-27T19:36:22.285801Z","steps":["trace[438086136] 'process raft request' (duration: 147.017345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:22.285903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.012852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-05-27T19:36:22.285928Z","caller":"traceutil/trace.go:171","msg":"trace[1475809167] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"147.444069ms","start":"2026-05-27T19:36:22.138472Z","end":"2026-05-27T19:36:22.285916Z","steps":["trace[1475809167] 'process raft request' (duration: 146.939722ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:22.285944Z","caller":"traceutil/trace.go:171","msg":"trace[472532339] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"147.928854ms","start":"2026-05-27T19:36:22.138004Z","end":"2026-05-27T19:36:22.285932Z","steps":["trace[472532339] 'process raft request' (duration: 147.347485ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:22.285933Z","caller":"traceutil/trace.go:171","msg":"trace[1966538753] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:265; }","duration":"178.084364ms","start":"2026-05-27T19:36:22.107841Z","end":"2026-05-27T19:36:22.285925Z","steps":["trace[1966538753] 'agreement among raft nodes before linearized reading' (duration: 178.036563ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:22.30123Z","caller":"traceutil/trace.go:171","msg":"trace[1957723034] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"102.403686ms","start":"2026-05-27T19:36:22.198808Z","end":"2026-05-27T19:36:22.301212Z","steps":["trace[1957723034] 'process raft request' (duration: 102.254871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:22.30131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.3652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-27T19:36:22.301377Z","caller":"traceutil/trace.go:171","msg":"trace[542101344] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:266; }","duration":"104.474003ms","start":"2026-05-27T19:36:22.196884Z","end":"2026-05-27T19:36:22.301358Z","steps":["trace[542101344] 'agreement among raft nodes before linearized reading' (duration: 104.322409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:23.321345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.5038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7301"} {"level":"info","ts":"2026-05-27T19:36:23.321478Z","caller":"traceutil/trace.go:171","msg":"trace[355054778] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:288; }","duration":"272.677065ms","start":"2026-05-27T19:36:23.048785Z","end":"2026-05-27T19:36:23.321462Z","steps":["trace[355054778] 'range keys from in-memory index tree' (duration: 272.406936ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:23.440511Z","caller":"traceutil/trace.go:171","msg":"trace[37754324] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"390.017417ms","start":"2026-05-27T19:36:23.050477Z","end":"2026-05-27T19:36:23.440495Z","steps":["trace[37754324] 'process raft request' (duration: 389.947774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:23.440627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:23.050451Z","time spent":"390.12426ms","remote":"127.0.0.1:47258","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-05-27T19:36:23.547092Z","caller":"traceutil/trace.go:171","msg":"trace[126626259] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"490.3047ms","start":"2026-05-27T19:36:23.05676Z","end":"2026-05-27T19:36:23.547065Z","steps":["trace[126626259] 'read index received' (duration: 383.863139ms)","trace[126626259] 'applied index is now lower than readState.Index' (duration: 106.440901ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:23.547158Z","caller":"traceutil/trace.go:171","msg":"trace[643960991] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"214.66017ms","start":"2026-05-27T19:36:23.332468Z","end":"2026-05-27T19:36:23.547128Z","steps":["trace[643960991] 'process raft request' (duration: 214.529546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:23.547277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.504517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-27T19:36:23.5473Z","caller":"traceutil/trace.go:171","msg":"trace[1108696455] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"490.570298ms","start":"2026-05-27T19:36:23.056721Z","end":"2026-05-27T19:36:23.547292Z","steps":["trace[1108696455] 'agreement among raft nodes before linearized reading' (duration: 490.509276ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:23.547314Z","caller":"traceutil/trace.go:171","msg":"trace[1924414072] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"496.576302ms","start":"2026-05-27T19:36:23.050732Z","end":"2026-05-27T19:36:23.547309Z","steps":["trace[1924414072] 'process raft request' (duration: 496.189639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:23.547324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:23.056701Z","time spent":"490.61722ms","remote":"127.0.0.1:47188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T19:36:23.54737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:23.050712Z","time spent":"496.615143ms","remote":"127.0.0.1:47258","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:36:23.54747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.821322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7110"} {"level":"info","ts":"2026-05-27T19:36:23.547488Z","caller":"traceutil/trace.go:171","msg":"trace[2092903568] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:289; }","duration":"105.878993ms","start":"2026-05-27T19:36:23.441602Z","end":"2026-05-27T19:36:23.547481Z","steps":["trace[2092903568] 'agreement among raft nodes before linearized reading' (duration: 105.813181ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:23.686157Z","caller":"traceutil/trace.go:171","msg":"trace[196764636] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:304; }","duration":"125.776363ms","start":"2026-05-27T19:36:23.560357Z","end":"2026-05-27T19:36:23.686134Z","steps":["trace[196764636] 'read index received' (duration: 100.052806ms)","trace[196764636] 'applied index is now lower than readState.Index' (duration: 25.722677ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:36:23.686353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.960199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-27T19:36:23.686408Z","caller":"traceutil/trace.go:171","msg":"trace[1417149999] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:291; }","duration":"126.057402ms","start":"2026-05-27T19:36:23.560335Z","end":"2026-05-27T19:36:23.686392Z","steps":["trace[1417149999] 'agreement among raft nodes before linearized reading' (duration: 125.910457ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:23.686374Z","caller":"traceutil/trace.go:171","msg":"trace[443306296] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"126.272149ms","start":"2026-05-27T19:36:23.560037Z","end":"2026-05-27T19:36:23.686309Z","steps":["trace[443306296] 'process raft request' (duration: 100.433638ms)","trace[443306296] 'compare' (duration: 25.563112ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:36:24.077027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.585264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:36:24.077209Z","caller":"traceutil/trace.go:171","msg":"trace[1092609119] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"246.715527ms","start":"2026-05-27T19:36:23.830458Z","end":"2026-05-27T19:36:24.077174Z","steps":["trace[1092609119] 'process raft request' (duration: 134.763992ms)","trace[1092609119] 'compare' (duration: 111.46483ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:36:24.077275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.441419ms","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-05-27T19:36:24.077329Z","caller":"traceutil/trace.go:171","msg":"trace[1028492517] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:297; }","duration":"228.534092ms","start":"2026-05-27T19:36:23.848784Z","end":"2026-05-27T19:36:24.077318Z","steps":["trace[1028492517] 'agreement among raft nodes before linearized reading' (duration: 228.433479ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:24.077888Z","caller":"traceutil/trace.go:171","msg":"trace[1110004373] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"228.306835ms","start":"2026-05-27T19:36:23.848823Z","end":"2026-05-27T19:36:24.077129Z","steps":["trace[1110004373] 'read index received' (duration: 116.344379ms)","trace[1110004373] 'applied index is now lower than readState.Index' (duration: 111.961016ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:24.253429Z","caller":"traceutil/trace.go:171","msg":"trace[1109484585] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"163.004626ms","start":"2026-05-27T19:36:24.090402Z","end":"2026-05-27T19:36:24.253407Z","steps":["trace[1109484585] 'process raft request' (duration: 162.958244ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:24.253709Z","caller":"traceutil/trace.go:171","msg":"trace[170045440] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"164.437852ms","start":"2026-05-27T19:36:24.089252Z","end":"2026-05-27T19:36:24.25369Z","steps":["trace[170045440] 'process raft request' (duration: 102.46316ms)","trace[170045440] 'compare' (duration: 61.490746ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:24.476159Z","caller":"traceutil/trace.go:171","msg":"trace[968182791] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"120.261606ms","start":"2026-05-27T19:36:24.355881Z","end":"2026-05-27T19:36:24.476142Z","steps":["trace[968182791] 'read index received' (duration: 39.998242ms)","trace[968182791] 'applied index is now lower than readState.Index' (duration: 80.262634ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:24.476248Z","caller":"traceutil/trace.go:171","msg":"trace[2143405673] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"129.80173ms","start":"2026-05-27T19:36:24.346438Z","end":"2026-05-27T19:36:24.47624Z","steps":["trace[2143405673] 'process raft request' (duration: 49.492455ms)","trace[2143405673] 'compare' (duration: 80.134069ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:36:24.476373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.477613ms","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-05-27T19:36:24.47641Z","caller":"traceutil/trace.go:171","msg":"trace[16686828] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:302; }","duration":"120.552915ms","start":"2026-05-27T19:36:24.355848Z","end":"2026-05-27T19:36:24.476401Z","steps":["trace[16686828] 'agreement among raft nodes before linearized reading' (duration: 120.483623ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:24.925488Z","caller":"traceutil/trace.go:171","msg":"trace[744512902] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:321; }","duration":"168.177339ms","start":"2026-05-27T19:36:24.757288Z","end":"2026-05-27T19:36:24.925465Z","steps":["trace[744512902] 'read index received' (duration: 167.832028ms)","trace[744512902] 'applied index is now lower than readState.Index' (duration: 344.261µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:24.925896Z","caller":"traceutil/trace.go:171","msg":"trace[1366916566] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"207.554562ms","start":"2026-05-27T19:36:24.718312Z","end":"2026-05-27T19:36:24.925867Z","steps":["trace[1366916566] 'process raft request' (duration: 207.023045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:24.925997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.679915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-27T19:36:24.926075Z","caller":"traceutil/trace.go:171","msg":"trace[956676911] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"205.472826ms","start":"2026-05-27T19:36:24.720584Z","end":"2026-05-27T19:36:24.926057Z","steps":["trace[956676911] 'process raft request' (duration: 204.843636ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:24.926448Z","caller":"traceutil/trace.go:171","msg":"trace[767111014] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:310; }","duration":"168.846051ms","start":"2026-05-27T19:36:24.757254Z","end":"2026-05-27T19:36:24.9261Z","steps":["trace[767111014] 'agreement among raft nodes before linearized reading' (duration: 168.661335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:24.926594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.973544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-27T19:36:24.925927Z","caller":"traceutil/trace.go:171","msg":"trace[369383720] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"208.374588ms","start":"2026-05-27T19:36:24.71744Z","end":"2026-05-27T19:36:24.925815Z","steps":["trace[369383720] 'process raft request' (duration: 207.753529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:24.926191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.069466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:36:24.926747Z","caller":"traceutil/trace.go:171","msg":"trace[790244616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:310; }","duration":"157.658875ms","start":"2026-05-27T19:36:24.76908Z","end":"2026-05-27T19:36:24.926739Z","steps":["trace[790244616] 'agreement among raft nodes before linearized reading' (duration: 157.009804ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:24.926644Z","caller":"traceutil/trace.go:171","msg":"trace[1996689563] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:310; }","duration":"119.046346ms","start":"2026-05-27T19:36:24.807582Z","end":"2026-05-27T19:36:24.926629Z","steps":["trace[1996689563] 'agreement among raft nodes before linearized reading' (duration: 118.988425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:24.926685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.444031ms","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-05-27T19:36:24.926952Z","caller":"traceutil/trace.go:171","msg":"trace[1455638028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:310; }","duration":"131.7375ms","start":"2026-05-27T19:36:24.795198Z","end":"2026-05-27T19:36:24.926936Z","steps":["trace[1455638028] 'agreement among raft nodes before linearized reading' (duration: 131.464431ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.042038Z","caller":"traceutil/trace.go:171","msg":"trace[703140665] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"109.032317ms","start":"2026-05-27T19:36:24.932982Z","end":"2026-05-27T19:36:25.042014Z","steps":["trace[703140665] 'process raft request' (duration: 94.260272ms)","trace[703140665] 'compare' (duration: 14.497286ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:25.042089Z","caller":"traceutil/trace.go:171","msg":"trace[1214763792] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:324; }","duration":"106.698733ms","start":"2026-05-27T19:36:24.935376Z","end":"2026-05-27T19:36:25.042075Z","steps":["trace[1214763792] 'read index received' (duration: 20.643536ms)","trace[1214763792] 'applied index is now lower than readState.Index' (duration: 86.054477ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:25.042142Z","caller":"traceutil/trace.go:171","msg":"trace[1991323832] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"103.340056ms","start":"2026-05-27T19:36:24.938796Z","end":"2026-05-27T19:36:25.042136Z","steps":["trace[1991323832] 'process raft request' (duration: 103.194381ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.043013Z","caller":"traceutil/trace.go:171","msg":"trace[482182720] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"103.916534ms","start":"2026-05-27T19:36:24.939051Z","end":"2026-05-27T19:36:25.042967Z","steps":["trace[482182720] 'process raft request' (duration: 102.995495ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.043362Z","caller":"traceutil/trace.go:171","msg":"trace[1780603916] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"104.39419ms","start":"2026-05-27T19:36:24.938942Z","end":"2026-05-27T19:36:25.043336Z","steps":["trace[1780603916] 'process raft request' (duration: 103.079538ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.043969Z","caller":"traceutil/trace.go:171","msg":"trace[1184337378] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"107.097475ms","start":"2026-05-27T19:36:24.936834Z","end":"2026-05-27T19:36:25.043932Z","steps":["trace[1184337378] 'process raft request' (duration: 105.066691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:25.044566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.432774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-05-27T19:36:25.044737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.15449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-05-27T19:36:25.044798Z","caller":"traceutil/trace.go:171","msg":"trace[331622617] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:318; }","duration":"109.421739ms","start":"2026-05-27T19:36:24.935352Z","end":"2026-05-27T19:36:25.044774Z","steps":["trace[331622617] 'agreement among raft nodes before linearized reading' (duration: 108.967855ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.044639Z","caller":"traceutil/trace.go:171","msg":"trace[2084038797] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:318; }","duration":"106.508486ms","start":"2026-05-27T19:36:24.938101Z","end":"2026-05-27T19:36:25.04461Z","steps":["trace[2084038797] 'agreement among raft nodes before linearized reading' (duration: 105.732641ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.046138Z","caller":"traceutil/trace.go:171","msg":"trace[1273732739] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"109.167881ms","start":"2026-05-27T19:36:24.936935Z","end":"2026-05-27T19:36:25.046103Z","steps":["trace[1273732739] 'process raft request' (duration: 105.00409ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.046687Z","caller":"traceutil/trace.go:171","msg":"trace[348972275] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"108.064515ms","start":"2026-05-27T19:36:24.938599Z","end":"2026-05-27T19:36:25.046663Z","steps":["trace[348972275] 'process raft request' (duration: 103.368467ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.252371Z","caller":"traceutil/trace.go:171","msg":"trace[794410923] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"135.241678ms","start":"2026-05-27T19:36:25.117099Z","end":"2026-05-27T19:36:25.252341Z","steps":["trace[794410923] 'process raft request' (duration: 60.33894ms)","trace[794410923] 'compare' (duration: 74.725973ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:25.252639Z","caller":"traceutil/trace.go:171","msg":"trace[916221110] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:343; }","duration":"130.580491ms","start":"2026-05-27T19:36:25.12203Z","end":"2026-05-27T19:36:25.25261Z","steps":["trace[916221110] 'read index received' (duration: 55.429135ms)","trace[916221110] 'applied index is now lower than readState.Index' (duration: 75.150036ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:25.25274Z","caller":"traceutil/trace.go:171","msg":"trace[2081154129] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"133.729791ms","start":"2026-05-27T19:36:25.118975Z","end":"2026-05-27T19:36:25.252705Z","steps":["trace[2081154129] 'process raft request' (duration: 133.458712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:25.252937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.881221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-27T19:36:25.253004Z","caller":"traceutil/trace.go:171","msg":"trace[572889034] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:331; }","duration":"130.990115ms","start":"2026-05-27T19:36:25.121997Z","end":"2026-05-27T19:36:25.252987Z","steps":["trace[572889034] 'agreement among raft nodes before linearized reading' (duration: 130.764368ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.592659Z","caller":"traceutil/trace.go:171","msg":"trace[1766052019] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:363; }","duration":"141.892428ms","start":"2026-05-27T19:36:25.450744Z","end":"2026-05-27T19:36:25.592636Z","steps":["trace[1766052019] 'read index received' (duration: 91.750719ms)","trace[1766052019] 'applied index is now lower than readState.Index' (duration: 50.140839ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:25.592727Z","caller":"traceutil/trace.go:171","msg":"trace[444211754] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"159.215683ms","start":"2026-05-27T19:36:25.433493Z","end":"2026-05-27T19:36:25.592709Z","steps":["trace[444211754] 'process raft request' (duration: 108.895798ms)","trace[444211754] 'compare' (duration: 50.033826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:36:25.592781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.025492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-05-27T19:36:25.592777Z","caller":"traceutil/trace.go:171","msg":"trace[1763844416] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"156.437336ms","start":"2026-05-27T19:36:25.436282Z","end":"2026-05-27T19:36:25.59272Z","steps":["trace[1763844416] 'process raft request' (duration: 156.289712ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:25.592867Z","caller":"traceutil/trace.go:171","msg":"trace[172834372] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:351; }","duration":"142.161247ms","start":"2026-05-27T19:36:25.450689Z","end":"2026-05-27T19:36:25.59285Z","steps":["trace[172834372] 'agreement among raft nodes before linearized reading' (duration: 142.037873ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:27.181501Z","caller":"traceutil/trace.go:171","msg":"trace[1917505391] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:370; }","duration":"125.869584ms","start":"2026-05-27T19:36:27.055604Z","end":"2026-05-27T19:36:27.181473Z","steps":["trace[1917505391] 'read index received' (duration: 125.678488ms)","trace[1917505391] 'applied index is now lower than readState.Index' (duration: 190.506µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:27.181618Z","caller":"traceutil/trace.go:171","msg":"trace[291552919] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"218.01444ms","start":"2026-05-27T19:36:26.963556Z","end":"2026-05-27T19:36:27.18157Z","steps":["trace[291552919] 'process raft request' (duration: 217.796723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:27.181766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.141869ms","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-05-27T19:36:27.181802Z","caller":"traceutil/trace.go:171","msg":"trace[1373997559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:357; }","duration":"123.218281ms","start":"2026-05-27T19:36:27.058573Z","end":"2026-05-27T19:36:27.181792Z","steps":["trace[1373997559] 'agreement among raft nodes before linearized reading' (duration: 123.077377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:27.18181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.155802ms","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-05-27T19:36:27.18198Z","caller":"traceutil/trace.go:171","msg":"trace[866452913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:357; }","duration":"126.40796ms","start":"2026-05-27T19:36:27.055532Z","end":"2026-05-27T19:36:27.18194Z","steps":["trace[866452913] 'agreement among raft nodes before linearized reading' (duration: 126.07593ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:35.513352Z","caller":"traceutil/trace.go:171","msg":"trace[346584000] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"673.77108ms","start":"2026-05-27T19:36:34.839554Z","end":"2026-05-27T19:36:35.513325Z","steps":["trace[346584000] 'process raft request' (duration: 673.621156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:35.513525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:34.839537Z","time spent":"673.896204ms","remote":"127.0.0.1:47334","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-05-27T19:36:35.845888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.14195ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T19:36:35.845999Z","caller":"traceutil/trace.go:171","msg":"trace[532913441] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:403; }","duration":"770.373457ms","start":"2026-05-27T19:36:35.075607Z","end":"2026-05-27T19:36:35.84598Z","steps":["trace[532913441] 'read index received' (duration: 437.604103ms)","trace[532913441] 'applied index is now lower than readState.Index' (duration: 332.767684ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:36:35.846155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.935961ms","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-05-27T19:36:35.846229Z","caller":"traceutil/trace.go:171","msg":"trace[2035774414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:388; }","duration":"625.024493ms","start":"2026-05-27T19:36:35.221163Z","end":"2026-05-27T19:36:35.846187Z","steps":["trace[2035774414] 'agreement among raft nodes before linearized reading' (duration: 624.91954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:35.846132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.525851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:36:35.846246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.982341ms","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-05-27T19:36:35.846274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:35.221141Z","time spent":"625.123517ms","remote":"127.0.0.1:47334","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-05-27T19:36:35.846321Z","caller":"traceutil/trace.go:171","msg":"trace[1869570880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"553.083893ms","start":"2026-05-27T19:36:35.293217Z","end":"2026-05-27T19:36:35.846301Z","steps":["trace[1869570880] 'agreement among raft nodes before linearized reading' (duration: 552.900097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:35.846341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.609146ms","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-05-27T19:36:35.846382Z","caller":"traceutil/trace.go:171","msg":"trace[355495141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:388; }","duration":"770.823129ms","start":"2026-05-27T19:36:35.075538Z","end":"2026-05-27T19:36:35.846361Z","steps":["trace[355495141] 'agreement among raft nodes before linearized reading' (duration: 770.50602ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:36:35.846453Z","caller":"traceutil/trace.go:171","msg":"trace[447333096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:388; }","duration":"554.794072ms","start":"2026-05-27T19:36:35.291632Z","end":"2026-05-27T19:36:35.846427Z","steps":["trace[447333096] 'agreement among raft nodes before linearized reading' (duration: 554.528125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:35.846456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:35.075513Z","time spent":"770.928832ms","remote":"127.0.0.1:47064","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-05-27T19:36:35.846512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:35.291611Z","time spent":"554.886104ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:36:35.846383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:35.293207Z","time spent":"553.157586ms","remote":"127.0.0.1:47334","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-05-27T19:36:48.173623Z","caller":"traceutil/trace.go:171","msg":"trace[2035201364] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"209.86244ms","start":"2026-05-27T19:36:47.963732Z","end":"2026-05-27T19:36:48.173594Z","steps":["trace[2035201364] 'process raft request' (duration: 194.949883ms)","trace[2035201364] 'compare' (duration: 14.550868ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:36:48.173648Z","caller":"traceutil/trace.go:171","msg":"trace[1674768781] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"209.910571ms","start":"2026-05-27T19:36:47.963721Z","end":"2026-05-27T19:36:48.173632Z","steps":["trace[1674768781] 'process raft request' (duration: 209.635694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:55.795104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.316396ms","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:5"} {"level":"info","ts":"2026-05-27T19:36:55.795183Z","caller":"traceutil/trace.go:171","msg":"trace[129456213] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:464; }","duration":"103.439879ms","start":"2026-05-27T19:36:55.691726Z","end":"2026-05-27T19:36:55.795166Z","steps":["trace[129456213] 'range keys from in-memory index tree' (duration: 103.181813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:58.120326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639525340528363355,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:36:58.621144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639525340528363355,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:36:59.122041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639525340528363355,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:36:59.533063Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.926588138s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:36:59.533659Z","caller":"traceutil/trace.go:171","msg":"trace[139037893] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.927311005s","start":"2026-05-27T19:36:57.606305Z","end":"2026-05-27T19:36:59.533616Z","steps":["trace[139037893] 'process raft request' (duration: 1.927112711s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:36:59.533845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:57.606288Z","time spent":"1.927449308s","remote":"127.0.0.1:47122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:37:00.07688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000251382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-27T19:37:00.076974Z","caller":"traceutil/trace.go:171","msg":"trace[1602666372] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000402656s","start":"2026-05-27T19:36:58.07655Z","end":"2026-05-27T19:37:00.076952Z","steps":["trace[1602666372] 'agreement among raft nodes before linearized reading' (duration: 2.000281473s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.077023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:58.07653Z","time spent":"2.000482047s","remote":"127.0.0.1:47068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:37:00.593853Z","caller":"traceutil/trace.go:171","msg":"trace[1311445000] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:549; }","duration":"2.973764879s","start":"2026-05-27T19:36:57.620068Z","end":"2026-05-27T19:37:00.593833Z","steps":["trace[1311445000] 'read index received' (duration: 1.914169148s)","trace[1311445000] 'applied index is now lower than readState.Index' (duration: 1.059595071s)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:00.593943Z","caller":"traceutil/trace.go:171","msg":"trace[1538110707] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"2.977406915s","start":"2026-05-27T19:36:57.616528Z","end":"2026-05-27T19:37:00.593935Z","steps":["trace[1538110707] 'process raft request' (duration: 2.892959269s)","trace[1538110707] 'compare' (duration: 84.001655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:00.594029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:57.616484Z","time spent":"2.977477846s","remote":"127.0.0.1:47122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:37:00.594125Z","caller":"traceutil/trace.go:171","msg":"trace[1886957156] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"2.967103113s","start":"2026-05-27T19:36:57.627015Z","end":"2026-05-27T19:37:00.594118Z","steps":["trace[1886957156] 'process raft request' (duration: 2.966640612s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.594157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:57.626985Z","time spent":"2.967151884s","remote":"127.0.0.1:47122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:37:00.594238Z","caller":"traceutil/trace.go:171","msg":"trace[2014208555] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"2.861254269s","start":"2026-05-27T19:36:57.732978Z","end":"2026-05-27T19:37:00.594232Z","steps":["trace[2014208555] 'process raft request' (duration: 2.860781609s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.594263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:57.732947Z","time spent":"2.86130321s","remote":"127.0.0.1:47122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:37:00.594392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.974326202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:28754"} {"level":"info","ts":"2026-05-27T19:37:00.594463Z","caller":"traceutil/trace.go:171","msg":"trace[1404802609] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:528; }","duration":"2.974421974s","start":"2026-05-27T19:36:57.62003Z","end":"2026-05-27T19:37:00.594452Z","steps":["trace[1404802609] 'agreement among raft nodes before linearized reading' (duration: 2.974270981s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.594488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:57.620009Z","time spent":"2.974472885s","remote":"127.0.0.1:47122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":28777,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-27T19:37:00.904511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.882685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:00.904738Z","caller":"traceutil/trace.go:171","msg":"trace[42122597] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.343643444s","start":"2026-05-27T19:36:59.561062Z","end":"2026-05-27T19:37:00.904705Z","steps":["trace[42122597] 'process raft request' (duration: 1.137425951s)","trace[42122597] 'compare' (duration: 205.691171ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:00.904851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:59.56102Z","time spent":"1.343784477s","remote":"127.0.0.1:47122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:37:00.905103Z","caller":"traceutil/trace.go:171","msg":"trace[889545021] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"823.934149ms","start":"2026-05-27T19:37:00.081126Z","end":"2026-05-27T19:37:00.90506Z","steps":["trace[889545021] 'process raft request' (duration: 823.53308ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:00.905224Z","caller":"traceutil/trace.go:171","msg":"trace[279211356] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:552; }","duration":"311.294938ms","start":"2026-05-27T19:37:00.59391Z","end":"2026-05-27T19:37:00.905205Z","steps":["trace[279211356] 'read index received' (duration: 104.369319ms)","trace[279211356] 'applied index is now lower than readState.Index' (duration: 206.923919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:00.905385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.240119717s","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-05-27T19:37:00.905461Z","caller":"traceutil/trace.go:171","msg":"trace[93348074] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"231.563641ms","start":"2026-05-27T19:37:00.673872Z","end":"2026-05-27T19:37:00.905436Z","steps":["trace[93348074] 'process raft request' (duration: 231.425728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.905465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.907345407s","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-05-27T19:37:00.90549Z","caller":"traceutil/trace.go:171","msg":"trace[935674571] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"300.680916ms","start":"2026-05-27T19:37:00.604775Z","end":"2026-05-27T19:37:00.905456Z","steps":["trace[935674571] 'process raft request' (duration: 300.307217ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:00.905576Z","caller":"traceutil/trace.go:171","msg":"trace[2040402292] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"238.832606ms","start":"2026-05-27T19:37:00.66672Z","end":"2026-05-27T19:37:00.905553Z","steps":["trace[2040402292] 'process raft request' (duration: 238.510379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.90549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.68876543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T19:37:00.905615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.309676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:00.905637Z","caller":"traceutil/trace.go:171","msg":"trace[808172319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"2.689038756s","start":"2026-05-27T19:36:58.216589Z","end":"2026-05-27T19:37:00.905628Z","steps":["trace[808172319] 'agreement among raft nodes before linearized reading' (duration: 2.688659067s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:00.905649Z","caller":"traceutil/trace.go:171","msg":"trace[1072829481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"820.375987ms","start":"2026-05-27T19:37:00.085264Z","end":"2026-05-27T19:37:00.90564Z","steps":["trace[1072829481] 'agreement among raft nodes before linearized reading' (duration: 820.296345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.905688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:00.085247Z","time spent":"820.435629ms","remote":"127.0.0.1:47064","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-05-27T19:37:00.905589Z","caller":"traceutil/trace.go:171","msg":"trace[235408974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"2.907506551s","start":"2026-05-27T19:36:57.998055Z","end":"2026-05-27T19:37:00.905562Z","steps":["trace[235408974] 'agreement among raft nodes before linearized reading' (duration: 2.907265316s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.905715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299883027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-27T19:37:00.90574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:57.99804Z","time spent":"2.907690875s","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T19:37:00.905773Z","caller":"traceutil/trace.go:171","msg":"trace[849017819] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:533; }","duration":"1.29998676s","start":"2026-05-27T19:36:59.605764Z","end":"2026-05-27T19:37:00.90575Z","steps":["trace[849017819] 'agreement among raft nodes before linearized reading' (duration: 1.299895368s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.905451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.688984815s","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-05-27T19:37:00.905812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:59.60574Z","time spent":"1.300063452s","remote":"127.0.0.1:47104","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.201\" "} {"level":"warn","ts":"2026-05-27T19:37:00.905689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:58.216564Z","time spent":"2.689115208s","remote":"127.0.0.1:47334","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-05-27T19:37:00.905682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:00.604753Z","time spent":"300.82302ms","remote":"127.0.0.1:47122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:37:00.905522Z","caller":"traceutil/trace.go:171","msg":"trace[285574205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"3.240188308s","start":"2026-05-27T19:36:57.665226Z","end":"2026-05-27T19:37:00.905414Z","steps":["trace[285574205] 'agreement among raft nodes before linearized reading' (duration: 3.240037765s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:00.906158Z","caller":"traceutil/trace.go:171","msg":"trace[1145127365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"2.689432035s","start":"2026-05-27T19:36:58.21638Z","end":"2026-05-27T19:37:00.905812Z","steps":["trace[1145127365] 'agreement among raft nodes before linearized reading' (duration: 2.688895993s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:00.906241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:58.21636Z","time spent":"2.689866255s","remote":"127.0.0.1:47334","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-05-27T19:37:00.906411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:00.081088Z","time spent":"824.614705ms","remote":"127.0.0.1:47148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:37:00.906259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:36:57.665195Z","time spent":"3.241021217s","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:37:01.072575Z","caller":"traceutil/trace.go:171","msg":"trace[756946971] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"135.940437ms","start":"2026-05-27T19:37:00.936591Z","end":"2026-05-27T19:37:01.072531Z","steps":["trace[756946971] 'process raft request' (duration: 73.242308ms)","trace[756946971] 'compare' (duration: 62.446903ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:01.60599Z","caller":"traceutil/trace.go:171","msg":"trace[945216827] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"103.271616ms","start":"2026-05-27T19:37:01.502698Z","end":"2026-05-27T19:37:01.605969Z","steps":["trace[945216827] 'process raft request' (duration: 44.478446ms)","trace[945216827] 'compare' (duration: 58.682978ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:01.780183Z","caller":"traceutil/trace.go:171","msg":"trace[626171559] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"142.142305ms","start":"2026-05-27T19:37:01.637947Z","end":"2026-05-27T19:37:01.780089Z","steps":["trace[626171559] 'process raft request' (duration: 105.730061ms)","trace[626171559] 'compare' (duration: 35.334939ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:01.780547Z","caller":"traceutil/trace.go:171","msg":"trace[1066146256] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"104.212947ms","start":"2026-05-27T19:37:01.676278Z","end":"2026-05-27T19:37:01.780491Z","steps":["trace[1066146256] 'process raft request' (duration: 104.139685ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:01.780677Z","caller":"traceutil/trace.go:171","msg":"trace[1425230055] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"134.879111ms","start":"2026-05-27T19:37:01.645767Z","end":"2026-05-27T19:37:01.780646Z","steps":["trace[1425230055] 'process raft request' (duration: 133.838158ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:05.406506Z","caller":"traceutil/trace.go:171","msg":"trace[1896321621] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"149.507206ms","start":"2026-05-27T19:37:05.256975Z","end":"2026-05-27T19:37:05.406482Z","steps":["trace[1896321621] 'process raft request' (duration: 148.952543ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:06.093674Z","caller":"traceutil/trace.go:171","msg":"trace[1284280670] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"195.388286ms","start":"2026-05-27T19:37:05.898246Z","end":"2026-05-27T19:37:06.093634Z","steps":["trace[1284280670] 'process raft request' (duration: 194.741572ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:06.709463Z","caller":"traceutil/trace.go:171","msg":"trace[953365330] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"132.114662ms","start":"2026-05-27T19:37:06.577315Z","end":"2026-05-27T19:37:06.70943Z","steps":["trace[953365330] 'process raft request' (duration: 131.514059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:06.927359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.182819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:06.927553Z","caller":"traceutil/trace.go:171","msg":"trace[1096020412] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"155.242895ms","start":"2026-05-27T19:37:06.772276Z","end":"2026-05-27T19:37:06.927518Z","steps":["trace[1096020412] 'process raft request' (duration: 30.78164ms)","trace[1096020412] 'compare' (duration: 123.949854ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:07.628283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.852506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:07.628514Z","caller":"traceutil/trace.go:171","msg":"trace[1673551922] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"507.509988ms","start":"2026-05-27T19:37:07.120986Z","end":"2026-05-27T19:37:07.628496Z","steps":["trace[1673551922] 'process raft request' (duration: 507.469477ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:07.628573Z","caller":"traceutil/trace.go:171","msg":"trace[1507791368] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"507.796514ms","start":"2026-05-27T19:37:07.120747Z","end":"2026-05-27T19:37:07.628543Z","steps":["trace[1507791368] 'process raft request' (duration: 507.62877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:07.628613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:07.120974Z","time spent":"507.580829ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:37:07.628742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:07.120711Z","time spent":"507.936547ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:37:07.628821Z","caller":"traceutil/trace.go:171","msg":"trace[1409590111] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"508.888738ms","start":"2026-05-27T19:37:07.119724Z","end":"2026-05-27T19:37:07.628613Z","steps":["trace[1409590111] 'process raft request' (duration: 331.630804ms)","trace[1409590111] 'compare' (duration: 176.569109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:07.629095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:07.119693Z","time spent":"509.287867ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:37:07.631003Z","caller":"traceutil/trace.go:171","msg":"trace[589519290] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"267.093422ms","start":"2026-05-27T19:37:07.363887Z","end":"2026-05-27T19:37:07.63098Z","steps":["trace[589519290] 'process raft request' (duration: 266.501759ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:09.455324Z","caller":"traceutil/trace.go:171","msg":"trace[864698181] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"798.008499ms","start":"2026-05-27T19:37:08.657288Z","end":"2026-05-27T19:37:09.455296Z","steps":["trace[864698181] 'process raft request' (duration: 797.038689ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:09.455226Z","caller":"traceutil/trace.go:171","msg":"trace[1161863834] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"379.770238ms","start":"2026-05-27T19:37:09.075424Z","end":"2026-05-27T19:37:09.455194Z","steps":["trace[1161863834] 'read index received' (duration: 378.615423ms)","trace[1161863834] 'applied index is now lower than readState.Index' (duration: 1.153565ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:09.455543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:08.657235Z","time spent":"798.199463ms","remote":"127.0.0.1:47170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:37:10.660206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497681421s","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-05-27T19:37:10.660361Z","caller":"traceutil/trace.go:171","msg":"trace[940513053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.497905886s","start":"2026-05-27T19:37:09.162415Z","end":"2026-05-27T19:37:10.660321Z","steps":["trace[940513053] 'agreement among raft nodes before linearized reading' (duration: 293.156288ms)","trace[940513053] 'range keys from in-memory index tree' (duration: 1.20436256s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:10.660421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:09.162391Z","time spent":"1.498015578s","remote":"127.0.0.1:47334","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-05-27T19:37:10.660674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58522916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:10.661692Z","caller":"traceutil/trace.go:171","msg":"trace[1726403532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.586268193s","start":"2026-05-27T19:37:09.075381Z","end":"2026-05-27T19:37:10.661649Z","steps":["trace[1726403532] 'agreement among raft nodes before linearized reading' (duration: 380.017873ms)","trace[1726403532] 'range keys from in-memory index tree' (duration: 1.205210387s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:10.661765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:09.075364Z","time spent":"1.586382525s","remote":"127.0.0.1:47068","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-05-27T19:37:10.660871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.104861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:10.662055Z","caller":"traceutil/trace.go:171","msg":"trace[1672761876] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"1.054987136s","start":"2026-05-27T19:37:09.60703Z","end":"2026-05-27T19:37:10.662017Z","steps":["trace[1672761876] 'read index received' (duration: 77.566938ms)","trace[1672761876] 'applied index is now lower than readState.Index' (duration: 977.416118ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:10.662262Z","caller":"traceutil/trace.go:171","msg":"trace[1619300178] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.144766853s","start":"2026-05-27T19:37:09.51747Z","end":"2026-05-27T19:37:10.662237Z","steps":["trace[1619300178] 'process raft request' (duration: 167.175361ms)","trace[1619300178] 'compare' (duration: 975.639121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:10.66235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027852769s","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-05-27T19:37:10.662367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028555544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-27T19:37:10.662364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055338634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T19:37:10.662414Z","caller":"traceutil/trace.go:171","msg":"trace[2026240141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.028643957s","start":"2026-05-27T19:37:09.633756Z","end":"2026-05-27T19:37:10.6624Z","steps":["trace[2026240141] 'agreement among raft nodes before linearized reading' (duration: 1.028420582s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:10.662411Z","caller":"traceutil/trace.go:171","msg":"trace[1861340171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"1.027930221s","start":"2026-05-27T19:37:09.634463Z","end":"2026-05-27T19:37:10.662393Z","steps":["trace[1861340171] 'agreement among raft nodes before linearized reading' (duration: 1.027709557s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:10.662425Z","caller":"traceutil/trace.go:171","msg":"trace[1889019212] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:619; }","duration":"1.055437646s","start":"2026-05-27T19:37:09.606968Z","end":"2026-05-27T19:37:10.662405Z","steps":["trace[1889019212] 'agreement among raft nodes before linearized reading' (duration: 1.055197651s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:10.662422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:09.517435Z","time spent":"1.144917567s","remote":"127.0.0.1:47316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:37:10.662447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:09.633738Z","time spent":"1.028701477s","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:37:10.662454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:09.606942Z","time spent":"1.055506357s","remote":"127.0.0.1:47104","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.201\" "} {"level":"warn","ts":"2026-05-27T19:37:10.662462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:09.63445Z","time spent":"1.027998223s","remote":"127.0.0.1:47334","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-05-27T19:37:10.662594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028689938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T19:37:10.662704Z","caller":"traceutil/trace.go:171","msg":"trace[1438428588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.028873201s","start":"2026-05-27T19:37:09.633803Z","end":"2026-05-27T19:37:10.662677Z","steps":["trace[1438428588] 'agreement among raft nodes before linearized reading' (duration: 1.028570635s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:10.662772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:09.633775Z","time spent":"1.028983554s","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T19:37:11.127618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:10.665252Z","time spent":"462.354728ms","remote":"127.0.0.1:47104","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-27T19:37:11.12793Z","caller":"traceutil/trace.go:171","msg":"trace[83514249] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"457.446385ms","start":"2026-05-27T19:37:10.670454Z","end":"2026-05-27T19:37:11.1279Z","steps":["trace[83514249] 'process raft request' (duration: 457.402574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:11.128154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:10.670435Z","time spent":"457.608198ms","remote":"127.0.0.1:47334","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-05-27T19:37:11.128487Z","caller":"traceutil/trace.go:171","msg":"trace[172632384] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"463.020542ms","start":"2026-05-27T19:37:10.665407Z","end":"2026-05-27T19:37:11.128427Z","steps":["trace[172632384] 'read index received' (duration: 374.102432ms)","trace[172632384] 'applied index is now lower than readState.Index' (duration: 88.91203ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:11.128599Z","caller":"traceutil/trace.go:171","msg":"trace[1512954435] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"460.471899ms","start":"2026-05-27T19:37:10.668113Z","end":"2026-05-27T19:37:11.128585Z","steps":["trace[1512954435] 'process raft request' (duration: 459.705713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:11.128824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:10.668103Z","time spent":"460.53156ms","remote":"127.0.0.1:47334","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-05-27T19:37:11.128825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.38787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-27T19:37:11.128966Z","caller":"traceutil/trace.go:171","msg":"trace[1829506159] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:623; }","duration":"463.591404ms","start":"2026-05-27T19:37:10.66536Z","end":"2026-05-27T19:37:11.128952Z","steps":["trace[1829506159] 'agreement among raft nodes before linearized reading' (duration: 463.309709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:11.12901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:10.665334Z","time spent":"463.665206ms","remote":"127.0.0.1:47288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-05-27T19:37:11.128538Z","caller":"traceutil/trace.go:171","msg":"trace[1402513045] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"460.593331ms","start":"2026-05-27T19:37:10.667918Z","end":"2026-05-27T19:37:11.128512Z","steps":["trace[1402513045] 'process raft request' (duration: 459.860156ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:11.129148Z","caller":"traceutil/trace.go:171","msg":"trace[1730545001] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"461.323886ms","start":"2026-05-27T19:37:10.667802Z","end":"2026-05-27T19:37:11.129126Z","steps":["trace[1730545001] 'process raft request' (duration: 459.768323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:11.129072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.860593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:37:11.129312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:10.667909Z","time spent":"461.276505ms","remote":"127.0.0.1:47334","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-05-27T19:37:11.129509Z","caller":"traceutil/trace.go:171","msg":"trace[1518492656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"457.294252ms","start":"2026-05-27T19:37:10.672193Z","end":"2026-05-27T19:37:11.129487Z","steps":["trace[1518492656] 'agreement among raft nodes before linearized reading' (duration: 456.677869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:11.129599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:10.672164Z","time spent":"457.392453ms","remote":"127.0.0.1:47064","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-05-27T19:37:11.12972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:10.667766Z","time spent":"461.432539ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:37:12.615757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.89858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:12.615845Z","caller":"traceutil/trace.go:171","msg":"trace[55791901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:640; }","duration":"249.029003ms","start":"2026-05-27T19:37:12.366797Z","end":"2026-05-27T19:37:12.615826Z","steps":["trace[55791901] 'range keys from in-memory index tree' (duration: 248.831618ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:13.397273Z","caller":"traceutil/trace.go:171","msg":"trace[1288296954] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"111.119681ms","start":"2026-05-27T19:37:13.28613Z","end":"2026-05-27T19:37:13.39725Z","steps":["trace[1288296954] 'process raft request' (duration: 110.771164ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:14.163987Z","caller":"traceutil/trace.go:171","msg":"trace[1094493741] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"130.45479ms","start":"2026-05-27T19:37:14.033498Z","end":"2026-05-27T19:37:14.163953Z","steps":["trace[1094493741] 'read index received' (duration: 110.938541ms)","trace[1094493741] 'applied index is now lower than readState.Index' (duration: 19.514829ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:14.164181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.661774ms","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-05-27T19:37:14.164242Z","caller":"traceutil/trace.go:171","msg":"trace[524860012] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:681; }","duration":"130.777437ms","start":"2026-05-27T19:37:14.033449Z","end":"2026-05-27T19:37:14.164227Z","steps":["trace[524860012] 'agreement among raft nodes before linearized reading' (duration: 130.617703ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:14.164083Z","caller":"traceutil/trace.go:171","msg":"trace[955490338] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"221.682416ms","start":"2026-05-27T19:37:13.942368Z","end":"2026-05-27T19:37:14.164051Z","steps":["trace[955490338] 'process raft request' (duration: 202.068035ms)","trace[955490338] 'compare' (duration: 19.347186ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:14.353433Z","caller":"traceutil/trace.go:171","msg":"trace[1431590696] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"119.427715ms","start":"2026-05-27T19:37:14.233975Z","end":"2026-05-27T19:37:14.353403Z","steps":["trace[1431590696] 'process raft request' (duration: 95.997605ms)","trace[1431590696] 'compare' (duration: 23.172684ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:14.369552Z","caller":"traceutil/trace.go:171","msg":"trace[168037665] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"123.787824ms","start":"2026-05-27T19:37:14.245724Z","end":"2026-05-27T19:37:14.369512Z","steps":["trace[168037665] 'process raft request' (duration: 123.642041ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:21.411706Z","caller":"traceutil/trace.go:171","msg":"trace[508565449] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"171.491441ms","start":"2026-05-27T19:37:21.240193Z","end":"2026-05-27T19:37:21.411684Z","steps":["trace[508565449] 'process raft request' (duration: 130.361929ms)","trace[508565449] 'compare' (duration: 40.778035ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:24.007098Z","caller":"traceutil/trace.go:171","msg":"trace[1971731064] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"191.05259ms","start":"2026-05-27T19:37:23.816017Z","end":"2026-05-27T19:37:24.00707Z","steps":["trace[1971731064] 'process raft request' (duration: 190.892937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:26.80137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639525340528363941,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:37:27.292389Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.144818559s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-27T19:37:27.744819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:27.079884Z","time spent":"664.927834ms","remote":"127.0.0.1:56220","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-27T19:37:27.745082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.297364ms","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-05-27T19:37:27.745174Z","caller":"traceutil/trace.go:171","msg":"trace[1675609507] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:781; }","duration":"118.416276ms","start":"2026-05-27T19:37:27.626742Z","end":"2026-05-27T19:37:27.745159Z","steps":["trace[1675609507] 'range keys from in-memory index tree' (duration: 118.281414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:27.750762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.017132ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T19:37:27.750924Z","caller":"traceutil/trace.go:171","msg":"trace[1543382152] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"1.449659295s","start":"2026-05-27T19:37:26.30124Z","end":"2026-05-27T19:37:27.750899Z","steps":["trace[1543382152] 'read index received' (duration: 991.413259ms)","trace[1543382152] 'applied index is now lower than readState.Index' (duration: 458.244256ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:27.751191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4499407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7rpql\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-27T19:37:27.751254Z","caller":"traceutil/trace.go:171","msg":"trace[898924545] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7rpql; range_end:; response_count:1; response_revision:781; }","duration":"1.450037952s","start":"2026-05-27T19:37:26.301196Z","end":"2026-05-27T19:37:27.751234Z","steps":["trace[898924545] 'agreement among raft nodes before linearized reading' (duration: 1.449839368s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:27.751304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:26.301179Z","time spent":"1.450112833s","remote":"127.0.0.1:47258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7rpql\" "} {"level":"warn","ts":"2026-05-27T19:37:27.751349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.469721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:27.751391Z","caller":"traceutil/trace.go:171","msg":"trace[1215421559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"535.535913ms","start":"2026-05-27T19:37:27.215843Z","end":"2026-05-27T19:37:27.751379Z","steps":["trace[1215421559] 'agreement among raft nodes before linearized reading' (duration: 535.453061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:27.751416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:27.215828Z","time spent":"535.582223ms","remote":"127.0.0.1:47064","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-05-27T19:37:27.75153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.459089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T19:37:27.751613Z","caller":"traceutil/trace.go:171","msg":"trace[1514784569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"490.579131ms","start":"2026-05-27T19:37:27.261012Z","end":"2026-05-27T19:37:27.751591Z","steps":["trace[1514784569] 'agreement among raft nodes before linearized reading' (duration: 490.373667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:27.751678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.553759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T19:37:27.751675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:27.260995Z","time spent":"490.666022ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:37:27.751707Z","caller":"traceutil/trace.go:171","msg":"trace[40511485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"491.6141ms","start":"2026-05-27T19:37:27.260085Z","end":"2026-05-27T19:37:27.751699Z","steps":["trace[40511485] 'agreement among raft nodes before linearized reading' (duration: 491.536248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:27.751672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.38297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-27T19:37:27.751763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.4712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:27.751788Z","caller":"traceutil/trace.go:171","msg":"trace[1103267392] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:781; }","duration":"676.51733ms","start":"2026-05-27T19:37:27.075264Z","end":"2026-05-27T19:37:27.751782Z","steps":["trace[1103267392] 'agreement among raft nodes before linearized reading' (duration: 676.48178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:27.751807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:27.075248Z","time spent":"676.553881ms","remote":"127.0.0.1:47552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-27T19:37:27.751801Z","caller":"traceutil/trace.go:171","msg":"trace[1639973049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"535.569043ms","start":"2026-05-27T19:37:27.216206Z","end":"2026-05-27T19:37:27.751775Z","steps":["trace[1639973049] 'agreement among raft nodes before linearized reading' (duration: 535.034003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:27.751604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043627586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-27T19:37:27.751863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:27.216178Z","time spent":"535.673175ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:37:27.751727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:27.260066Z","time spent":"491.656791ms","remote":"127.0.0.1:47334","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-05-27T19:37:27.75214Z","caller":"traceutil/trace.go:171","msg":"trace[1490648033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"1.044035724s","start":"2026-05-27T19:37:26.707887Z","end":"2026-05-27T19:37:27.751922Z","steps":["trace[1490648033] 'agreement among raft nodes before linearized reading' (duration: 1.04330894s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:27.752224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:26.707867Z","time spent":"1.044339709s","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T19:37:27.752512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.360233ms","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-05-27T19:37:27.752595Z","caller":"traceutil/trace.go:171","msg":"trace[1275919658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"307.463104ms","start":"2026-05-27T19:37:27.445113Z","end":"2026-05-27T19:37:27.752576Z","steps":["trace[1275919658] 'agreement among raft nodes before linearized reading' (duration: 306.033457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:27.752664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:27.445093Z","time spent":"307.540605ms","remote":"127.0.0.1:47334","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-05-27T19:37:31.996571Z","caller":"traceutil/trace.go:171","msg":"trace[1431109988] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"133.75486ms","start":"2026-05-27T19:37:31.862783Z","end":"2026-05-27T19:37:31.996538Z","steps":["trace[1431109988] 'process raft request' (duration: 133.595317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:32.232245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.91148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:32.232358Z","caller":"traceutil/trace.go:171","msg":"trace[1022590042] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"200.621907ms","start":"2026-05-27T19:37:32.031723Z","end":"2026-05-27T19:37:32.232345Z","steps":["trace[1022590042] 'read index received' (duration: 3.504354ms)","trace[1022590042] 'applied index is now lower than readState.Index' (duration: 197.116783ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:32.232435Z","caller":"traceutil/trace.go:171","msg":"trace[1950172958] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"226.615028ms","start":"2026-05-27T19:37:32.005788Z","end":"2026-05-27T19:37:32.232403Z","steps":["trace[1950172958] 'process raft request' (duration: 29.482305ms)","trace[1950172958] 'compare' (duration: 196.742966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:32.232751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.028084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-05-27T19:37:32.232786Z","caller":"traceutil/trace.go:171","msg":"trace[1033404877] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:834; }","duration":"201.094156ms","start":"2026-05-27T19:37:32.031681Z","end":"2026-05-27T19:37:32.232776Z","steps":["trace[1033404877] 'agreement among raft nodes before linearized reading' (duration: 200.698459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:32.619757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.198773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-05-27T19:37:32.619863Z","caller":"traceutil/trace.go:171","msg":"trace[56642570] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:834; }","duration":"177.348225ms","start":"2026-05-27T19:37:32.44249Z","end":"2026-05-27T19:37:32.619838Z","steps":["trace[56642570] 'range keys from in-memory index tree' (duration: 176.665073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:34.818984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.364685ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-27T19:37:35.510377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.936513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:35.510449Z","caller":"traceutil/trace.go:171","msg":"trace[988038588] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"272.305217ms","start":"2026-05-27T19:37:35.238133Z","end":"2026-05-27T19:37:35.510438Z","steps":["trace[988038588] 'process raft request' (duration: 83.243202ms)","trace[988038588] 'compare' (duration: 188.8126ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:35.518036Z","caller":"traceutil/trace.go:171","msg":"trace[2013937375] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"278.61206ms","start":"2026-05-27T19:37:35.239406Z","end":"2026-05-27T19:37:35.518018Z","steps":["trace[2013937375] 'process raft request' (duration: 278.433177ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:35.518104Z","caller":"traceutil/trace.go:171","msg":"trace[1418206823] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"276.499392ms","start":"2026-05-27T19:37:35.24158Z","end":"2026-05-27T19:37:35.51808Z","steps":["trace[1418206823] 'process raft request' (duration: 276.37196ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:35.518181Z","caller":"traceutil/trace.go:171","msg":"trace[318862055] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"252.107308ms","start":"2026-05-27T19:37:35.266064Z","end":"2026-05-27T19:37:35.518171Z","steps":["trace[318862055] 'process raft request' (duration: 251.932745ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:35.71931Z","caller":"traceutil/trace.go:171","msg":"trace[747395260] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"193.243539ms","start":"2026-05-27T19:37:35.526037Z","end":"2026-05-27T19:37:35.719281Z","steps":["trace[747395260] 'process raft request' (duration: 192.12716ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:35.73198Z","caller":"traceutil/trace.go:171","msg":"trace[1551588079] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"183.268812ms","start":"2026-05-27T19:37:35.548684Z","end":"2026-05-27T19:37:35.731953Z","steps":["trace[1551588079] 'process raft request' (duration: 183.149ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:36.277753Z","caller":"traceutil/trace.go:171","msg":"trace[1893926637] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"469.548779ms","start":"2026-05-27T19:37:35.808181Z","end":"2026-05-27T19:37:36.277729Z","steps":["trace[1893926637] 'read index received' (duration: 419.164507ms)","trace[1893926637] 'applied index is now lower than readState.Index' (duration: 50.382912ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:36.277816Z","caller":"traceutil/trace.go:171","msg":"trace[1184542744] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"538.922144ms","start":"2026-05-27T19:37:35.738872Z","end":"2026-05-27T19:37:36.277794Z","steps":["trace[1184542744] 'process raft request' (duration: 488.486111ms)","trace[1184542744] 'compare' (duration: 50.121537ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:36.278955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:35.738858Z","time spent":"540.026623ms","remote":"127.0.0.1:47258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4762,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:37:36.279104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.096924ms","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-05-27T19:37:36.279144Z","caller":"traceutil/trace.go:171","msg":"trace[1882642444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:860; }","duration":"463.180946ms","start":"2026-05-27T19:37:35.81595Z","end":"2026-05-27T19:37:36.279131Z","steps":["trace[1882642444] 'agreement among raft nodes before linearized reading' (duration: 463.097304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:36.279154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.376033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:37:36.27918Z","caller":"traceutil/trace.go:171","msg":"trace[1890856504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:860; }","duration":"461.416024ms","start":"2026-05-27T19:37:35.817756Z","end":"2026-05-27T19:37:36.279172Z","steps":["trace[1890856504] 'agreement among raft nodes before linearized reading' (duration: 461.334923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:36.279199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:35.817751Z","time spent":"461.442865ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:37:36.279232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.493315ms","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-05-27T19:37:36.279257Z","caller":"traceutil/trace.go:171","msg":"trace[73651549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:860; }","duration":"461.518096ms","start":"2026-05-27T19:37:35.817733Z","end":"2026-05-27T19:37:36.279251Z","steps":["trace[73651549] 'agreement among raft nodes before linearized reading' (duration: 461.459225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:36.279277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:35.817703Z","time spent":"461.569557ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:37:36.27928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.477525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-27T19:37:36.279177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:35.815934Z","time spent":"463.233536ms","remote":"127.0.0.1:47334","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-05-27T19:37:36.279326Z","caller":"traceutil/trace.go:171","msg":"trace[1420501295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:860; }","duration":"461.533666ms","start":"2026-05-27T19:37:35.817781Z","end":"2026-05-27T19:37:36.279314Z","steps":["trace[1420501295] 'agreement among raft nodes before linearized reading' (duration: 461.395093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:36.279362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:35.817776Z","time spent":"461.579297ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T19:37:36.278016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.822034ms","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-05-27T19:37:36.279508Z","caller":"traceutil/trace.go:171","msg":"trace[101689246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:860; }","duration":"471.356921ms","start":"2026-05-27T19:37:35.808138Z","end":"2026-05-27T19:37:36.279495Z","steps":["trace[101689246] 'agreement among raft nodes before linearized reading' (duration: 469.783773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:36.279541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:35.808118Z","time spent":"471.414352ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:37:36.754943Z","caller":"traceutil/trace.go:171","msg":"trace[12664749] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"472.252485ms","start":"2026-05-27T19:37:36.282655Z","end":"2026-05-27T19:37:36.754907Z","steps":["trace[12664749] 'process raft request' (duration: 425.999617ms)","trace[12664749] 'compare' (duration: 46.009884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:36.755165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:36.282638Z","time spent":"472.425018ms","remote":"127.0.0.1:47334","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-05-27T19:37:36.776676Z","caller":"traceutil/trace.go:171","msg":"trace[1569014557] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"493.96522ms","start":"2026-05-27T19:37:36.282688Z","end":"2026-05-27T19:37:36.776653Z","steps":["trace[1569014557] 'process raft request' (duration: 493.782437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:36.776834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:36.282667Z","time spent":"494.080201ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:37:36.777275Z","caller":"traceutil/trace.go:171","msg":"trace[945791112] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"493.498901ms","start":"2026-05-27T19:37:36.283756Z","end":"2026-05-27T19:37:36.777255Z","steps":["trace[945791112] 'process raft request' (duration: 493.467201ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:36.777453Z","caller":"traceutil/trace.go:171","msg":"trace[160369844] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"494.690772ms","start":"2026-05-27T19:37:36.282751Z","end":"2026-05-27T19:37:36.777441Z","steps":["trace[160369844] 'process raft request' (duration: 493.862797ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:36.777553Z","caller":"traceutil/trace.go:171","msg":"trace[1634776596] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"493.752366ms","start":"2026-05-27T19:37:36.283731Z","end":"2026-05-27T19:37:36.777483Z","steps":["trace[1634776596] 'process raft request' (duration: 493.4362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:36.777569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:36.282744Z","time spent":"494.743044ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:37:36.777676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:36.283721Z","time spent":"493.877778ms","remote":"127.0.0.1:47334","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-05-27T19:37:36.777693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:36.283728Z","time spent":"493.612393ms","remote":"127.0.0.1:47334","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-05-27T19:37:37.628457Z","caller":"traceutil/trace.go:171","msg":"trace[2045819183] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"115.94994ms","start":"2026-05-27T19:37:37.512486Z","end":"2026-05-27T19:37:37.628436Z","steps":["trace[2045819183] 'process raft request' (duration: 115.800617ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:37.645747Z","caller":"traceutil/trace.go:171","msg":"trace[266758539] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"121.422456ms","start":"2026-05-27T19:37:37.5243Z","end":"2026-05-27T19:37:37.645723Z","steps":["trace[266758539] 'process raft request' (duration: 115.538822ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:38.307165Z","caller":"traceutil/trace.go:171","msg":"trace[1406425695] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"263.472208ms","start":"2026-05-27T19:37:38.043671Z","end":"2026-05-27T19:37:38.307143Z","steps":["trace[1406425695] 'process raft request' (duration: 263.332575ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:38.316522Z","caller":"traceutil/trace.go:171","msg":"trace[870593118] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:906; }","duration":"245.620905ms","start":"2026-05-27T19:37:38.070882Z","end":"2026-05-27T19:37:38.316503Z","steps":["trace[870593118] 'read index received' (duration: 236.204631ms)","trace[870593118] 'applied index is now lower than readState.Index' (duration: 9.415294ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:38.316605Z","caller":"traceutil/trace.go:171","msg":"trace[1038589236] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"256.22993ms","start":"2026-05-27T19:37:38.060345Z","end":"2026-05-27T19:37:38.316575Z","steps":["trace[1038589236] 'process raft request' (duration: 256.050797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:38.316657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.757357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:38.316695Z","caller":"traceutil/trace.go:171","msg":"trace[1340129561] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:873; }","duration":"245.837279ms","start":"2026-05-27T19:37:38.070845Z","end":"2026-05-27T19:37:38.316682Z","steps":["trace[1340129561] 'agreement among raft nodes before linearized reading' (duration: 245.767558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:38.322526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.230441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:38.322599Z","caller":"traceutil/trace.go:171","msg":"trace[2135756184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"248.328662ms","start":"2026-05-27T19:37:38.074254Z","end":"2026-05-27T19:37:38.322582Z","steps":["trace[2135756184] 'agreement among raft nodes before linearized reading' (duration: 248.19701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:39.704336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.952482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:39.705094Z","caller":"traceutil/trace.go:171","msg":"trace[1366006926] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"299.741421ms","start":"2026-05-27T19:37:39.405323Z","end":"2026-05-27T19:37:39.705064Z","steps":["trace[1366006926] 'process raft request' (duration: 39.916934ms)","trace[1366006926] 'compare' (duration: 258.505054ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:40.158095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.22031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:37:40.158283Z","caller":"traceutil/trace.go:171","msg":"trace[1541751761] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"752.498176ms","start":"2026-05-27T19:37:39.405742Z","end":"2026-05-27T19:37:40.158241Z","steps":["trace[1541751761] 'process raft request' (duration: 439.024832ms)","trace[1541751761] 'compare' (duration: 313.035717ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:40.158335Z","caller":"traceutil/trace.go:171","msg":"trace[684929906] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"507.983285ms","start":"2026-05-27T19:37:39.650331Z","end":"2026-05-27T19:37:40.158315Z","steps":["trace[684929906] 'process raft request' (duration: 507.887194ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:40.158313Z","caller":"traceutil/trace.go:171","msg":"trace[1379856018] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"550.122528ms","start":"2026-05-27T19:37:39.608167Z","end":"2026-05-27T19:37:40.15829Z","steps":["trace[1379856018] 'read index received' (duration: 236.745826ms)","trace[1379856018] 'applied index is now lower than readState.Index' (duration: 313.373102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:40.158494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:39.405728Z","time spent":"752.658349ms","remote":"127.0.0.1:47338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:37:40.158608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.807683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:37:40.158651Z","caller":"traceutil/trace.go:171","msg":"trace[490415607] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:903; }","duration":"430.889785ms","start":"2026-05-27T19:37:39.727747Z","end":"2026-05-27T19:37:40.158637Z","steps":["trace[490415607] 'agreement among raft nodes before linearized reading' (duration: 430.824044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:40.158683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:39.727723Z","time spent":"430.951706ms","remote":"127.0.0.1:47288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-05-27T19:37:40.158452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:39.650305Z","time spent":"508.084497ms","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:37:40.160398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.230044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T19:37:40.160699Z","caller":"traceutil/trace.go:171","msg":"trace[2008281823] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:903; }","duration":"552.57308ms","start":"2026-05-27T19:37:39.608105Z","end":"2026-05-27T19:37:40.160678Z","steps":["trace[2008281823] 'agreement among raft nodes before linearized reading' (duration: 550.24224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:40.160758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:39.60808Z","time spent":"552.660981ms","remote":"127.0.0.1:47104","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.201\" "} {"level":"info","ts":"2026-05-27T19:37:47.091713Z","caller":"traceutil/trace.go:171","msg":"trace[1847839562] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"223.798403ms","start":"2026-05-27T19:37:46.867865Z","end":"2026-05-27T19:37:47.091663Z","steps":["trace[1847839562] 'process raft request' (duration: 168.392222ms)","trace[1847839562] 'compare' (duration: 55.158207ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:47.091848Z","caller":"traceutil/trace.go:171","msg":"trace[403281271] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"223.530019ms","start":"2026-05-27T19:37:46.868294Z","end":"2026-05-27T19:37:47.091824Z","steps":["trace[403281271] 'process raft request' (duration: 223.283665ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:47.091925Z","caller":"traceutil/trace.go:171","msg":"trace[648294344] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"223.612411ms","start":"2026-05-27T19:37:46.868307Z","end":"2026-05-27T19:37:47.091919Z","steps":["trace[648294344] 'process raft request' (duration: 223.345126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:49.847023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.42117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-27T19:37:49.847128Z","caller":"traceutil/trace.go:171","msg":"trace[1601410354] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:983; }","duration":"126.556742ms","start":"2026-05-27T19:37:49.720547Z","end":"2026-05-27T19:37:49.847104Z","steps":["trace[1601410354] 'range keys from in-memory index tree' (duration: 126.295919ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:53.728393Z","caller":"traceutil/trace.go:171","msg":"trace[414795301] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"106.563348ms","start":"2026-05-27T19:37:53.621814Z","end":"2026-05-27T19:37:53.728377Z","steps":["trace[414795301] 'process raft request' (duration: 106.452296ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:55.365345Z","caller":"traceutil/trace.go:171","msg":"trace[1553332393] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"209.951581ms","start":"2026-05-27T19:37:55.155372Z","end":"2026-05-27T19:37:55.365323Z","steps":["trace[1553332393] 'read index received' (duration: 209.803689ms)","trace[1553332393] 'applied index is now lower than readState.Index' (duration: 147.122µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:55.365424Z","caller":"traceutil/trace.go:171","msg":"trace[1389364190] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"211.968144ms","start":"2026-05-27T19:37:55.153431Z","end":"2026-05-27T19:37:55.365399Z","steps":["trace[1389364190] 'process raft request' (duration: 211.75533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:55.365558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.165105ms","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-05-27T19:37:55.3656Z","caller":"traceutil/trace.go:171","msg":"trace[637413893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"210.262107ms","start":"2026-05-27T19:37:55.155324Z","end":"2026-05-27T19:37:55.365587Z","steps":["trace[637413893] 'agreement among raft nodes before linearized reading' (duration: 210.090814ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:55.368832Z","caller":"traceutil/trace.go:171","msg":"trace[227517123] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"213.303295ms","start":"2026-05-27T19:37:55.155509Z","end":"2026-05-27T19:37:55.368812Z","steps":["trace[227517123] 'process raft request' (duration: 213.206313ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:02.481311Z","caller":"traceutil/trace.go:171","msg":"trace[841133509] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"122.532172ms","start":"2026-05-27T19:38:02.358747Z","end":"2026-05-27T19:38:02.481279Z","steps":["trace[841133509] 'process raft request' (duration: 122.336019ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.686047Z","caller":"traceutil/trace.go:171","msg":"trace[1518523089] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"196.690541ms","start":"2026-05-27T19:38:04.489334Z","end":"2026-05-27T19:38:04.686025Z","steps":["trace[1518523089] 'process raft request' (duration: 196.550709ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.799863Z","caller":"traceutil/trace.go:171","msg":"trace[2052651518] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"295.838868ms","start":"2026-05-27T19:38:04.503985Z","end":"2026-05-27T19:38:04.799824Z","steps":["trace[2052651518] 'process raft request' (duration: 258.76198ms)","trace[2052651518] 'compare' (duration: 36.875155ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:05.619671Z","caller":"traceutil/trace.go:171","msg":"trace[1454903791] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"102.437751ms","start":"2026-05-27T19:38:05.517199Z","end":"2026-05-27T19:38:05.619637Z","steps":["trace[1454903791] 'process raft request' (duration: 102.281999ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:11.447731Z","caller":"traceutil/trace.go:171","msg":"trace[944832815] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"170.000326ms","start":"2026-05-27T19:38:11.277706Z","end":"2026-05-27T19:38:11.447706Z","steps":["trace[944832815] 'process raft request' (duration: 164.199569ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:24.025186Z","caller":"traceutil/trace.go:171","msg":"trace[986895122] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"171.898188ms","start":"2026-05-27T19:38:23.853268Z","end":"2026-05-27T19:38:24.025166Z","steps":["trace[986895122] 'read index received' (duration: 171.748076ms)","trace[986895122] 'applied index is now lower than readState.Index' (duration: 149.372µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:24.025244Z","caller":"traceutil/trace.go:171","msg":"trace[745316133] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"285.157296ms","start":"2026-05-27T19:38:23.740052Z","end":"2026-05-27T19:38:24.02521Z","steps":["trace[745316133] 'process raft request' (duration: 285.007553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:24.025367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.737473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:38:24.025401Z","caller":"traceutil/trace.go:171","msg":"trace[291312144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1097; }","duration":"118.810634ms","start":"2026-05-27T19:38:23.906582Z","end":"2026-05-27T19:38:24.025392Z","steps":["trace[291312144] 'agreement among raft nodes before linearized reading' (duration: 118.683192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:24.025665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.370904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:38:24.025757Z","caller":"traceutil/trace.go:171","msg":"trace[655013714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1097; }","duration":"172.507616ms","start":"2026-05-27T19:38:23.853228Z","end":"2026-05-27T19:38:24.025736Z","steps":["trace[655013714] 'agreement among raft nodes before linearized reading' (duration: 172.015309ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:24.141503Z","caller":"traceutil/trace.go:171","msg":"trace[918540467] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"112.4596ms","start":"2026-05-27T19:38:24.028997Z","end":"2026-05-27T19:38:24.141457Z","steps":["trace[918540467] 'process raft request' (duration: 38.298102ms)","trace[918540467] 'compare' (duration: 73.842004ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:24.141528Z","caller":"traceutil/trace.go:171","msg":"trace[2079398493] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"112.196296ms","start":"2026-05-27T19:38:24.029311Z","end":"2026-05-27T19:38:24.141507Z","steps":["trace[2079398493] 'process raft request' (duration: 111.972453ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:27.805139Z","caller":"traceutil/trace.go:171","msg":"trace[2135343548] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"119.075426ms","start":"2026-05-27T19:38:27.686039Z","end":"2026-05-27T19:38:27.805114Z","steps":["trace[2135343548] 'process raft request' (duration: 118.915714ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:29.485136Z","caller":"traceutil/trace.go:171","msg":"trace[1406361155] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"133.549557ms","start":"2026-05-27T19:38:29.351566Z","end":"2026-05-27T19:38:29.485116Z","steps":["trace[1406361155] 'read index received' (duration: 133.392025ms)","trace[1406361155] 'applied index is now lower than readState.Index' (duration: 156.472µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:29.485319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.7341ms","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-05-27T19:38:29.485359Z","caller":"traceutil/trace.go:171","msg":"trace[1034478291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1114; }","duration":"133.864352ms","start":"2026-05-27T19:38:29.351486Z","end":"2026-05-27T19:38:29.485351Z","steps":["trace[1034478291] 'agreement among raft nodes before linearized reading' (duration: 133.71377ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:29.485398Z","caller":"traceutil/trace.go:171","msg":"trace[163223866] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"356.74467ms","start":"2026-05-27T19:38:29.128624Z","end":"2026-05-27T19:38:29.485369Z","steps":["trace[163223866] 'process raft request' (duration: 356.365415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:29.485539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:29.1286Z","time spent":"356.863061ms","remote":"127.0.0.1:47334","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-05-27T19:38:41.127813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.161065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:41.127924Z","caller":"traceutil/trace.go:171","msg":"trace[428629419] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1154; }","duration":"130.356657ms","start":"2026-05-27T19:38:40.997547Z","end":"2026-05-27T19:38:41.127903Z","steps":["trace[428629419] 'count revisions from in-memory index tree' (duration: 129.81342ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:41.740098Z","caller":"traceutil/trace.go:171","msg":"trace[188579322] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"150.565608ms","start":"2026-05-27T19:38:41.589506Z","end":"2026-05-27T19:38:41.740072Z","steps":["trace[188579322] 'process raft request' (duration: 150.410946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:42.001635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.058206ms","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-05-27T19:38:42.001694Z","caller":"traceutil/trace.go:171","msg":"trace[335325868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1155; }","duration":"131.164828ms","start":"2026-05-27T19:38:41.870516Z","end":"2026-05-27T19:38:42.001681Z","steps":["trace[335325868] 'range keys from in-memory index tree' (duration: 130.847073ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:56.667357Z","caller":"traceutil/trace.go:171","msg":"trace[1369708275] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"158.25795ms","start":"2026-05-27T19:38:56.509077Z","end":"2026-05-27T19:38:56.667335Z","steps":["trace[1369708275] 'read index received' (duration: 157.986376ms)","trace[1369708275] 'applied index is now lower than readState.Index' (duration: 270.534µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:56.667478Z","caller":"traceutil/trace.go:171","msg":"trace[493780845] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"181.709222ms","start":"2026-05-27T19:38:56.485732Z","end":"2026-05-27T19:38:56.667442Z","steps":["trace[493780845] 'process raft request' (duration: 181.411998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:56.667769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.484652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:38:56.667819Z","caller":"traceutil/trace.go:171","msg":"trace[793374106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"158.767537ms","start":"2026-05-27T19:38:56.509039Z","end":"2026-05-27T19:38:56.667807Z","steps":["trace[793374106] 'agreement among raft nodes before linearized reading' (duration: 158.392112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:56.959705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.779963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:38:56.959811Z","caller":"traceutil/trace.go:171","msg":"trace[1559690028] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"287.330184ms","start":"2026-05-27T19:38:56.672458Z","end":"2026-05-27T19:38:56.959788Z","steps":["trace[1559690028] 'process raft request' (duration: 140.387209ms)","trace[1559690028] 'compare' (duration: 146.569931ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:13.697631Z","caller":"traceutil/trace.go:171","msg":"trace[1621791420] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"136.741193ms","start":"2026-05-27T19:39:13.560848Z","end":"2026-05-27T19:39:13.697589Z","steps":["trace[1621791420] 'process raft request' (duration: 135.562508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:13.697827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.391265ms","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-05-27T19:39:13.697907Z","caller":"traceutil/trace.go:171","msg":"trace[1975229785] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1288; }","duration":"135.500597ms","start":"2026-05-27T19:39:13.562392Z","end":"2026-05-27T19:39:13.697893Z","steps":["trace[1975229785] 'agreement among raft nodes before linearized reading' (duration: 135.270084ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:13.697622Z","caller":"traceutil/trace.go:171","msg":"trace[174749390] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"135.131582ms","start":"2026-05-27T19:39:13.562416Z","end":"2026-05-27T19:39:13.697548Z","steps":["trace[174749390] 'read index received' (duration: 133.928817ms)","trace[174749390] 'applied index is now lower than readState.Index' (duration: 1.201085ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:39:25.412937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.693861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:25.413016Z","caller":"traceutil/trace.go:171","msg":"trace[1974955972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"337.828242ms","start":"2026-05-27T19:39:25.075172Z","end":"2026-05-27T19:39:25.413001Z","steps":["trace[1974955972] 'range keys from in-memory index tree' (duration: 337.57822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:25.413051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:25.075149Z","time spent":"337.895394ms","remote":"127.0.0.1:47064","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-05-27T19:39:25.478983Z","caller":"traceutil/trace.go:171","msg":"trace[1380001436] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"283.924774ms","start":"2026-05-27T19:39:25.195021Z","end":"2026-05-27T19:39:25.478946Z","steps":["trace[1380001436] 'read index received' (duration: 283.883674ms)","trace[1380001436] 'applied index is now lower than readState.Index' (duration: 39.68µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:25.479006Z","caller":"traceutil/trace.go:171","msg":"trace[977874823] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"287.794612ms","start":"2026-05-27T19:39:25.191181Z","end":"2026-05-27T19:39:25.478976Z","steps":["trace[977874823] 'process raft request' (duration: 287.60828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:25.479234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.197728ms","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-05-27T19:39:25.479275Z","caller":"traceutil/trace.go:171","msg":"trace[686022159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1335; }","duration":"284.294748ms","start":"2026-05-27T19:39:25.194968Z","end":"2026-05-27T19:39:25.479263Z","steps":["trace[686022159] 'agreement among raft nodes before linearized reading' (duration: 284.136866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:48.820331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.297898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:39:48.820411Z","caller":"traceutil/trace.go:171","msg":"trace[877503750] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1416; }","duration":"139.431709ms","start":"2026-05-27T19:39:48.680962Z","end":"2026-05-27T19:39:48.820393Z","steps":["trace[877503750] 'count revisions from in-memory index tree' (duration: 139.204427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:48.820624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.049504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-public/\" range_end:\"/registry/deployments/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:48.82076Z","caller":"traceutil/trace.go:171","msg":"trace[1674958051] range","detail":"{range_begin:/registry/deployments/kube-public/; range_end:/registry/deployments/kube-public0; response_count:0; response_revision:1416; }","duration":"100.208355ms","start":"2026-05-27T19:39:48.720522Z","end":"2026-05-27T19:39:48.82073Z","steps":["trace[1674958051] 'range keys from in-memory index tree' (duration: 100.015353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:48.820811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.370638ms","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-05-27T19:39:48.820905Z","caller":"traceutil/trace.go:171","msg":"trace[1424790742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1416; }","duration":"100.506899ms","start":"2026-05-27T19:39:48.720375Z","end":"2026-05-27T19:39:48.820881Z","steps":["trace[1424790742] 'range keys from in-memory index tree' (duration: 100.221985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:48.821634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.336357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:48.821693Z","caller":"traceutil/trace.go:171","msg":"trace[677467390] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:1416; }","duration":"101.579531ms","start":"2026-05-27T19:39:48.720099Z","end":"2026-05-27T19:39:48.821678Z","steps":["trace[677467390] 'range keys from in-memory index tree' (duration: 100.217686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:48.821662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.130911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/envoy-gateway-system/\" range_end:\"/registry/events/envoy-gateway-system0\" limit:500 ","response":"range_response_count:15 size:12285"} {"level":"info","ts":"2026-05-27T19:39:48.822014Z","caller":"traceutil/trace.go:171","msg":"trace[1487582903] range","detail":"{range_begin:/registry/events/envoy-gateway-system/; range_end:/registry/events/envoy-gateway-system0; response_count:15; response_revision:1416; }","duration":"136.445614ms","start":"2026-05-27T19:39:48.685474Z","end":"2026-05-27T19:39:48.82192Z","steps":["trace[1487582903] 'range keys from in-memory index tree' (duration: 135.098978ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:05.724882Z","caller":"traceutil/trace.go:171","msg":"trace[249620174] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"107.094955ms","start":"2026-05-27T19:40:05.617743Z","end":"2026-05-27T19:40:05.724838Z","steps":["trace[249620174] 'process raft request' (duration: 106.812605ms)"],"step_count":1}