{"level":"warn","ts":"2026-05-20T17:27:45.234677Z","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-20T17:27:45.234815Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-20T17:27:45.2349Z","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-20T17:27:45.234913Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-05-20T17:27:45.234938Z","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-20T17:27:45.23577Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-05-20T17:27:45.235861Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-20T17:27:45.23993Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.826904ms"} {"level":"info","ts":"2026-05-20T17:27:45.247108Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-05-20T17:27:45.247231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T17:27:45.247265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-05-20T17:27:45.247288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T17:27:45.247297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-05-20T17:27:45.247336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-05-20T17:27:45.254102Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T17:27:45.2554Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T17:27:45.256887Z","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-20T17:27:45.259938Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T17:27:45.260055Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T17:27:45.260193Z","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-20T17:27:45.260277Z","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-20T17:27:45.260288Z","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-20T17:27:45.261518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-05-20T17:27:45.262064Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-05-20T17:27:45.265419Z","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-20T17:27:45.265601Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T17:27:45.265631Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T17:27:45.265687Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-05-20T17:27:45.265698Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-05-20T17:27:45.44823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T17:27:45.448286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T17:27:45.448301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-05-20T17:27:45.448313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-05-20T17:27:45.448319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-05-20T17:27:45.448331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-05-20T17:27:45.448338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-05-20T17:27:45.870955Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T17:27:45.892637Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T17:27:45.892639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T17:27:45.892797Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T17:27:45.893457Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T17:27:45.89353Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T17:27:45.894379Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T17:27:45.897041Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"warn","ts":"2026-05-20T17:27:46.149297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.612163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-20T17:27:46.149394Z","caller":"traceutil/trace.go:171","msg":"trace[859649606] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"116.756676ms","start":"2026-05-20T17:27:46.032626Z","end":"2026-05-20T17:27:46.149383Z","steps":["trace[859649606] 'agreement among raft nodes before linearized reading' (duration: 116.624203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.149393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.686244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-20T17:27:46.149503Z","caller":"traceutil/trace.go:171","msg":"trace[689220951] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"116.806268ms","start":"2026-05-20T17:27:46.032644Z","end":"2026-05-20T17:27:46.149451Z","steps":["trace[689220951] 'agreement among raft nodes before linearized reading' (duration: 116.700195ms)"],"step_count":1} 2026/05/20 17:27:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-20T17:27:46.194976Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T17:27:46.195171Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T17:27:46.19526Z","caller":"traceutil/trace.go:171","msg":"trace[1062652312] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"259.390728ms","start":"2026-05-20T17:27:45.935851Z","end":"2026-05-20T17:27:46.195242Z","steps":["trace[1062652312] 'read index received' (duration: 259.06733ms)","trace[1062652312] 'applied index is now lower than readState.Index' (duration: 322.318µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:27:46.195281Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-20T17:27:46.195501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.569182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.195567Z","caller":"traceutil/trace.go:171","msg":"trace[532585124] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"259.666655ms","start":"2026-05-20T17:27:45.935891Z","end":"2026-05-20T17:27:46.195557Z","steps":["trace[532585124] 'agreement among raft nodes before linearized reading' (duration: 259.509791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.195554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.871886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.195597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.943419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.195709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.39011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.195707Z","caller":"traceutil/trace.go:171","msg":"trace[1027713023] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"119.104084ms","start":"2026-05-20T17:27:46.076587Z","end":"2026-05-20T17:27:46.195691Z","steps":["trace[1027713023] 'agreement among raft nodes before linearized reading' (duration: 118.795676ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.195744Z","caller":"traceutil/trace.go:171","msg":"trace[338553142] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"154.457862ms","start":"2026-05-20T17:27:46.041277Z","end":"2026-05-20T17:27:46.195735Z","steps":["trace[338553142] 'agreement among raft nodes before linearized reading' (duration: 154.409191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.195781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.521874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.195807Z","caller":"traceutil/trace.go:171","msg":"trace[44185622] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"154.589255ms","start":"2026-05-20T17:27:46.041211Z","end":"2026-05-20T17:27:46.1958Z","steps":["trace[44185622] 'agreement among raft nodes before linearized reading' (duration: 154.550724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.195751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.834969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.195869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.944458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.195886Z","caller":"traceutil/trace.go:171","msg":"trace[597238429] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"259.996322ms","start":"2026-05-20T17:27:45.935847Z","end":"2026-05-20T17:27:46.195843Z","steps":["trace[597238429] 'agreement among raft nodes before linearized reading' (duration: 259.450229ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.195898Z","caller":"traceutil/trace.go:171","msg":"trace[1988302782] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"180.03166ms","start":"2026-05-20T17:27:46.015859Z","end":"2026-05-20T17:27:46.195891Z","steps":["trace[1988302782] 'agreement among raft nodes before linearized reading' (duration: 179.982718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.195961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.71771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.195948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.197476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.196051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.36434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.196085Z","caller":"traceutil/trace.go:171","msg":"trace[1841013621] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"128.657478ms","start":"2026-05-20T17:27:46.067417Z","end":"2026-05-20T17:27:46.196074Z","steps":["trace[1841013621] 'agreement among raft nodes before linearized reading' (duration: 128.614147ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.196068Z","caller":"traceutil/trace.go:171","msg":"trace[1627411724] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"141.455872ms","start":"2026-05-20T17:27:46.054591Z","end":"2026-05-20T17:27:46.196047Z","steps":["trace[1627411724] 'agreement among raft nodes before linearized reading' (duration: 140.912379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.196127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.234769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.196206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.569076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.195987Z","caller":"traceutil/trace.go:171","msg":"trace[936128156] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"128.756861ms","start":"2026-05-20T17:27:46.067224Z","end":"2026-05-20T17:27:46.195981Z","steps":["trace[936128156] 'agreement among raft nodes before linearized reading' (duration: 128.71713ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.196216Z","caller":"traceutil/trace.go:171","msg":"trace[421275322] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"104.356603ms","start":"2026-05-20T17:27:46.091848Z","end":"2026-05-20T17:27:46.196205Z","steps":["trace[421275322] 'agreement among raft nodes before linearized reading' (duration: 104.208459ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.196238Z","caller":"traceutil/trace.go:171","msg":"trace[1203633178] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"141.613607ms","start":"2026-05-20T17:27:46.054617Z","end":"2026-05-20T17:27:46.19623Z","steps":["trace[1203633178] 'agreement among raft nodes before linearized reading' (duration: 141.569745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.196111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.060544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.196261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.540555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.19629Z","caller":"traceutil/trace.go:171","msg":"trace[1122312059] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"104.271081ms","start":"2026-05-20T17:27:46.09201Z","end":"2026-05-20T17:27:46.196281Z","steps":["trace[1122312059] 'agreement among raft nodes before linearized reading' (duration: 104.028694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.196299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.065659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.196306Z","caller":"traceutil/trace.go:171","msg":"trace[1622081985] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"119.620257ms","start":"2026-05-20T17:27:46.076675Z","end":"2026-05-20T17:27:46.196295Z","steps":["trace[1622081985] 'agreement among raft nodes before linearized reading' (duration: 119.563366ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.195661Z","caller":"traceutil/trace.go:171","msg":"trace[1046899850] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"179.986768ms","start":"2026-05-20T17:27:46.015645Z","end":"2026-05-20T17:27:46.195632Z","steps":["trace[1046899850] 'agreement among raft nodes before linearized reading' (duration: 179.775613ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.196324Z","caller":"traceutil/trace.go:171","msg":"trace[1223722042] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"206.08972ms","start":"2026-05-20T17:27:45.990227Z","end":"2026-05-20T17:27:46.196317Z","steps":["trace[1223722042] 'agreement among raft nodes before linearized reading' (duration: 206.051179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.196322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.174089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.196357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.741802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.196381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.195699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.196391Z","caller":"traceutil/trace.go:171","msg":"trace[733524667] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"205.802874ms","start":"2026-05-20T17:27:45.990581Z","end":"2026-05-20T17:27:46.196384Z","steps":["trace[733524667] 'agreement among raft nodes before linearized reading' (duration: 205.759043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.196399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.478503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-20T17:27:46.196442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.039328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.196439Z","caller":"traceutil/trace.go:171","msg":"trace[910432715] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"192.23115ms","start":"2026-05-20T17:27:46.004174Z","end":"2026-05-20T17:27:46.196405Z","steps":["trace[910432715] 'agreement among raft nodes before linearized reading' (duration: 192.180969ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.196393Z","caller":"traceutil/trace.go:171","msg":"trace[52170440] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"192.25788ms","start":"2026-05-20T17:27:46.004123Z","end":"2026-05-20T17:27:46.196381Z","steps":["trace[52170440] 'agreement among raft nodes before linearized reading' (duration: 192.165088ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.19647Z","caller":"traceutil/trace.go:171","msg":"trace[2043945203] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"230.085839ms","start":"2026-05-20T17:27:45.966377Z","end":"2026-05-20T17:27:46.196463Z","steps":["trace[2043945203] 'agreement among raft nodes before linearized reading' (duration: 230.047669ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:27:46.196462Z","caller":"traceutil/trace.go:171","msg":"trace[1078140263] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"220.528334ms","start":"2026-05-20T17:27:45.975889Z","end":"2026-05-20T17:27:46.196417Z","steps":["trace[1078140263] 'agreement among raft nodes before linearized reading' (duration: 220.479803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:27:46.196537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.488813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T17:27:46.196568Z","caller":"traceutil/trace.go:171","msg":"trace[1052695801] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"220.522344ms","start":"2026-05-20T17:27:45.976038Z","end":"2026-05-20T17:27:46.19656Z","steps":["trace[1052695801] 'agreement among raft nodes before linearized reading' (duration: 220.448282ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:12.463705Z","caller":"traceutil/trace.go:171","msg":"trace[1659255982] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"237.618324ms","start":"2026-05-20T17:28:12.226066Z","end":"2026-05-20T17:28:12.463684Z","steps":["trace[1659255982] 'process raft request' (duration: 237.43999ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.498643Z","caller":"traceutil/trace.go:171","msg":"trace[26916645] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"134.869743ms","start":"2026-05-20T17:28:14.363752Z","end":"2026-05-20T17:28:14.498622Z","steps":["trace[26916645] 'process raft request' (duration: 134.755069ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.585578Z","caller":"traceutil/trace.go:171","msg":"trace[1679572340] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"220.239626ms","start":"2026-05-20T17:28:14.365302Z","end":"2026-05-20T17:28:14.585542Z","steps":["trace[1679572340] 'process raft request' (duration: 218.906634ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.587536Z","caller":"traceutil/trace.go:171","msg":"trace[1573224083] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"213.328ms","start":"2026-05-20T17:28:14.373966Z","end":"2026-05-20T17:28:14.587294Z","steps":["trace[1573224083] 'process raft request' (duration: 211.62235ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.587558Z","caller":"traceutil/trace.go:171","msg":"trace[2023303387] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"213.35357ms","start":"2026-05-20T17:28:14.373912Z","end":"2026-05-20T17:28:14.587266Z","steps":["trace[2023303387] 'process raft request' (duration: 211.62432ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.587697Z","caller":"traceutil/trace.go:171","msg":"trace[1874643648] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"207.552003ms","start":"2026-05-20T17:28:14.37976Z","end":"2026-05-20T17:28:14.587312Z","steps":["trace[1874643648] 'process raft request' (duration: 205.877793ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.588169Z","caller":"traceutil/trace.go:171","msg":"trace[1242567243] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"204.413799ms","start":"2026-05-20T17:28:14.383594Z","end":"2026-05-20T17:28:14.588008Z","steps":["trace[1242567243] 'process raft request' (duration: 202.278309ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.587904Z","caller":"traceutil/trace.go:171","msg":"trace[941007588] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"221.909895ms","start":"2026-05-20T17:28:14.365387Z","end":"2026-05-20T17:28:14.587297Z","steps":["trace[941007588] 'process raft request' (duration: 220.088153ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.588555Z","caller":"traceutil/trace.go:171","msg":"trace[128483419] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"208.515596ms","start":"2026-05-20T17:28:14.3798Z","end":"2026-05-20T17:28:14.588316Z","steps":["trace[128483419] 'process raft request' (duration: 206.026917ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.595485Z","caller":"traceutil/trace.go:171","msg":"trace[1173229479] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:506; }","duration":"128.021249ms","start":"2026-05-20T17:28:14.467422Z","end":"2026-05-20T17:28:14.595443Z","steps":["trace[1173229479] 'read index received' (duration: 31.109502ms)","trace[1173229479] 'applied index is now lower than readState.Index' (duration: 96.910927ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:28:14.595541Z","caller":"traceutil/trace.go:171","msg":"trace[1648555451] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"196.192582ms","start":"2026-05-20T17:28:14.399299Z","end":"2026-05-20T17:28:14.595491Z","steps":["trace[1648555451] 'process raft request' (duration: 192.3161ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.595591Z","caller":"traceutil/trace.go:171","msg":"trace[309633414] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"174.637369ms","start":"2026-05-20T17:28:14.420917Z","end":"2026-05-20T17:28:14.595554Z","steps":["trace[309633414] 'process raft request' (duration: 174.466925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:14.595604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.167372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-20T17:28:14.595657Z","caller":"traceutil/trace.go:171","msg":"trace[1964965082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"128.261445ms","start":"2026-05-20T17:28:14.467384Z","end":"2026-05-20T17:28:14.595645Z","steps":["trace[1964965082] 'agreement among raft nodes before linearized reading' (duration: 128.139872ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:14.595657Z","caller":"traceutil/trace.go:171","msg":"trace[585510627] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"199.187314ms","start":"2026-05-20T17:28:14.392531Z","end":"2026-05-20T17:28:14.591719Z","steps":["trace[585510627] 'process raft request' (duration: 197.342209ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:15.228254Z","caller":"traceutil/trace.go:171","msg":"trace[2136730885] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"148.361454ms","start":"2026-05-20T17:28:15.079842Z","end":"2026-05-20T17:28:15.228203Z","steps":["trace[2136730885] 'process raft request' (duration: 147.660687ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:15.271221Z","caller":"traceutil/trace.go:171","msg":"trace[1110733162] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"134.395431ms","start":"2026-05-20T17:28:15.136796Z","end":"2026-05-20T17:28:15.271192Z","steps":["trace[1110733162] 'process raft request' (duration: 134.248178ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:15.435047Z","caller":"traceutil/trace.go:171","msg":"trace[1676261472] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"171.804692ms","start":"2026-05-20T17:28:15.263207Z","end":"2026-05-20T17:28:15.435012Z","steps":["trace[1676261472] 'process raft request' (duration: 130.733174ms)","trace[1676261472] 'compare' (duration: 40.906224ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:28:15.436538Z","caller":"traceutil/trace.go:171","msg":"trace[908434076] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"155.067153ms","start":"2026-05-20T17:28:15.281452Z","end":"2026-05-20T17:28:15.436519Z","steps":["trace[908434076] 'process raft request' (duration: 154.399937ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:15.442679Z","caller":"traceutil/trace.go:171","msg":"trace[319012427] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"145.433184ms","start":"2026-05-20T17:28:15.297148Z","end":"2026-05-20T17:28:15.442582Z","steps":["trace[319012427] 'read index received' (duration: 96.938518ms)","trace[319012427] 'applied index is now lower than readState.Index' (duration: 48.493166ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:28:15.44295Z","caller":"traceutil/trace.go:171","msg":"trace[1958273327] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"138.762785ms","start":"2026-05-20T17:28:15.30415Z","end":"2026-05-20T17:28:15.442912Z","steps":["trace[1958273327] 'process raft request' (duration: 132.949237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:15.443488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.308204ms","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-20T17:28:15.443529Z","caller":"traceutil/trace.go:171","msg":"trace[1448993080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"146.400577ms","start":"2026-05-20T17:28:15.297119Z","end":"2026-05-20T17:28:15.44352Z","steps":["trace[1448993080] 'agreement among raft nodes before linearized reading' (duration: 145.670349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:16.514164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872867711804276,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T17:28:16.720403Z","caller":"traceutil/trace.go:171","msg":"trace[1700613664] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"714.189119ms","start":"2026-05-20T17:28:16.006188Z","end":"2026-05-20T17:28:16.720377Z","steps":["trace[1700613664] 'process raft request' (duration: 713.807749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:16.720956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:16.006161Z","time spent":"714.316482ms","remote":"127.0.0.1:43938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:28:16.72705Z","caller":"traceutil/trace.go:171","msg":"trace[1184969216] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"713.712218ms","start":"2026-05-20T17:28:16.013314Z","end":"2026-05-20T17:28:16.727026Z","steps":["trace[1184969216] 'read index received' (duration: 706.553787ms)","trace[1184969216] 'applied index is now lower than readState.Index' (duration: 7.157361ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:28:16.727136Z","caller":"traceutil/trace.go:171","msg":"trace[1271330030] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"628.446756ms","start":"2026-05-20T17:28:16.098661Z","end":"2026-05-20T17:28:16.727107Z","steps":["trace[1271330030] 'process raft request' (duration: 628.085087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:16.727295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.957633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"warn","ts":"2026-05-20T17:28:16.727311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:16.098621Z","time spent":"628.594079ms","remote":"127.0.0.1:43938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:28:16.727334Z","caller":"traceutil/trace.go:171","msg":"trace[126118102] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:565; }","duration":"714.042056ms","start":"2026-05-20T17:28:16.01328Z","end":"2026-05-20T17:28:16.727322Z","steps":["trace[126118102] 'agreement among raft nodes before linearized reading' (duration: 713.864691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:16.727375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:16.01327Z","time spent":"714.096337ms","remote":"127.0.0.1:43938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":31149,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-20T17:28:16.727401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.492779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T17:28:16.72747Z","caller":"traceutil/trace.go:171","msg":"trace[573955403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:565; }","duration":"107.639063ms","start":"2026-05-20T17:28:16.619811Z","end":"2026-05-20T17:28:16.72745Z","steps":["trace[573955403] 'agreement among raft nodes before linearized reading' (duration: 107.420897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:16.727434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.379315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:16.727567Z","caller":"traceutil/trace.go:171","msg":"trace[1174525132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"248.596391ms","start":"2026-05-20T17:28:16.478936Z","end":"2026-05-20T17:28:16.727532Z","steps":["trace[1174525132] 'agreement among raft nodes before linearized reading' (duration: 248.306344ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:21.673007Z","caller":"traceutil/trace.go:171","msg":"trace[1981959724] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"238.253835ms","start":"2026-05-20T17:28:21.434725Z","end":"2026-05-20T17:28:21.672979Z","steps":["trace[1981959724] 'read index received' (duration: 237.151988ms)","trace[1981959724] 'applied index is now lower than readState.Index' (duration: 1.101137ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:28:21.673144Z","caller":"traceutil/trace.go:171","msg":"trace[1831973548] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"424.628633ms","start":"2026-05-20T17:28:21.248505Z","end":"2026-05-20T17:28:21.673133Z","steps":["trace[1831973548] 'process raft request' (duration: 423.892405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:21.673335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:21.248486Z","time spent":"424.687985ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:28:21.67353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.160847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:21.673645Z","caller":"traceutil/trace.go:171","msg":"trace[1255181643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"192.332681ms","start":"2026-05-20T17:28:21.481289Z","end":"2026-05-20T17:28:21.673621Z","steps":["trace[1255181643] 'agreement among raft nodes before linearized reading' (duration: 192.099095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:21.67392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.146908ms","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-20T17:28:21.673979Z","caller":"traceutil/trace.go:171","msg":"trace[652880258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"153.344133ms","start":"2026-05-20T17:28:21.520619Z","end":"2026-05-20T17:28:21.673963Z","steps":["trace[652880258] 'agreement among raft nodes before linearized reading' (duration: 153.032885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:21.674339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.568556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pl9qv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-20T17:28:21.674757Z","caller":"traceutil/trace.go:171","msg":"trace[914898176] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pl9qv; range_end:; response_count:1; response_revision:596; }","duration":"239.842393ms","start":"2026-05-20T17:28:21.434655Z","end":"2026-05-20T17:28:21.674497Z","steps":["trace[914898176] 'agreement among raft nodes before linearized reading' (duration: 238.978313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:22.177551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.514985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:28:22.177974Z","caller":"traceutil/trace.go:171","msg":"trace[1843839877] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"446.409121ms","start":"2026-05-20T17:28:21.731548Z","end":"2026-05-20T17:28:22.177957Z","steps":["trace[1843839877] 'process raft request' (duration: 446.36642ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:22.177953Z","caller":"traceutil/trace.go:171","msg":"trace[394297277] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"496.748291ms","start":"2026-05-20T17:28:21.68117Z","end":"2026-05-20T17:28:22.177918Z","steps":["trace[394297277] 'process raft request' (duration: 88.58639ms)","trace[394297277] 'compare' (duration: 407.366432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:28:22.178222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:21.68114Z","time spent":"496.979096ms","remote":"127.0.0.1:44176","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-20T17:28:22.178392Z","caller":"traceutil/trace.go:171","msg":"trace[1938117297] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"478.019004ms","start":"2026-05-20T17:28:21.700364Z","end":"2026-05-20T17:28:22.178383Z","steps":["trace[1938117297] 'process raft request' (duration: 477.475282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:22.178497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:21.700347Z","time spent":"478.119647ms","remote":"127.0.0.1:44086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:28:22.178239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.735513ms","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-20T17:28:22.178701Z","caller":"traceutil/trace.go:171","msg":"trace[473153189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"495.234604ms","start":"2026-05-20T17:28:21.683446Z","end":"2026-05-20T17:28:22.178681Z","steps":["trace[473153189] 'agreement among raft nodes before linearized reading' (duration: 494.656901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:22.178746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:21.683427Z","time spent":"495.308516ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T17:28:22.178084Z","caller":"traceutil/trace.go:171","msg":"trace[766654094] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"494.574909ms","start":"2026-05-20T17:28:21.6835Z","end":"2026-05-20T17:28:22.178075Z","steps":["trace[766654094] 'read index received' (duration: 86.266455ms)","trace[766654094] 'applied index is now lower than readState.Index' (duration: 408.307494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:28:22.178048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:21.73152Z","time spent":"446.501624ms","remote":"127.0.0.1:44278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T17:28:24.258416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:23.415017Z","time spent":"843.384595ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-20T17:28:24.258579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533563664s","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-20T17:28:24.258769Z","caller":"traceutil/trace.go:171","msg":"trace[1389542236] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:599; }","duration":"1.533787009s","start":"2026-05-20T17:28:22.724952Z","end":"2026-05-20T17:28:24.258739Z","steps":["trace[1389542236] 'range keys from in-memory index tree' (duration: 1.533539382s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:24.260113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.880103706s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:28:24.260512Z","caller":"traceutil/trace.go:171","msg":"trace[900256200] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"2.076889092s","start":"2026-05-20T17:28:22.183586Z","end":"2026-05-20T17:28:24.260475Z","steps":["trace[900256200] 'process raft request' (duration: 196.332485ms)","trace[900256200] 'compare' (duration: 1.878607281s)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:28:24.260514Z","caller":"traceutil/trace.go:171","msg":"trace[7019235] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"2.072318724s","start":"2026-05-20T17:28:22.188169Z","end":"2026-05-20T17:28:24.260488Z","steps":["trace[7019235] 'read index received' (duration: 191.745397ms)","trace[7019235] 'applied index is now lower than readState.Index' (duration: 1.880571837s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:28:24.260704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:22.183566Z","time spent":"2.077029405s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:28:24.275365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45994857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T17:28:24.275426Z","caller":"traceutil/trace.go:171","msg":"trace[673898301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"1.460056963s","start":"2026-05-20T17:28:22.815352Z","end":"2026-05-20T17:28:24.275409Z","steps":["trace[673898301] 'agreement among raft nodes before linearized reading' (duration: 1.459872618s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:24.275415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0872286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T17:28:24.275466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:22.815329Z","time spent":"1.460128865s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-20T17:28:24.27548Z","caller":"traceutil/trace.go:171","msg":"trace[1749415456] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:600; }","duration":"2.087318091s","start":"2026-05-20T17:28:22.188146Z","end":"2026-05-20T17:28:24.275464Z","steps":["trace[1749415456] 'agreement among raft nodes before linearized reading' (duration: 2.072418776s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:24.275518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:22.188132Z","time spent":"2.087377963s","remote":"127.0.0.1:44426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-05-20T17:28:24.275523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.775058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:24.275584Z","caller":"traceutil/trace.go:171","msg":"trace[608778151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"794.861881ms","start":"2026-05-20T17:28:23.480705Z","end":"2026-05-20T17:28:24.275566Z","steps":["trace[608778151] 'agreement among raft nodes before linearized reading' (duration: 794.757208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:24.275621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:23.48069Z","time spent":"794.924622ms","remote":"127.0.0.1:43882","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-20T17:28:24.275636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206857962s","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-20T17:28:24.275666Z","caller":"traceutil/trace.go:171","msg":"trace[703757932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.206923954s","start":"2026-05-20T17:28:23.068732Z","end":"2026-05-20T17:28:24.275656Z","steps":["trace[703757932] 'agreement among raft nodes before linearized reading' (duration: 1.206832392s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:24.275693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:28:23.068709Z","time spent":"1.206977844s","remote":"127.0.0.1:44176","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-20T17:28:25.846534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.462099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T17:28:25.846567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.373902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:25.846658Z","caller":"traceutil/trace.go:171","msg":"trace[1943030545] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:635; }","duration":"178.623303ms","start":"2026-05-20T17:28:25.668013Z","end":"2026-05-20T17:28:25.846636Z","steps":["trace[1943030545] 'range keys from in-memory index tree' (duration: 178.394257ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:25.846678Z","caller":"traceutil/trace.go:171","msg":"trace[1375677978] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:635; }","duration":"242.561986ms","start":"2026-05-20T17:28:25.604095Z","end":"2026-05-20T17:28:25.846657Z","steps":["trace[1375677978] 'range keys from in-memory index tree' (duration: 242.30756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:25.84671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.703842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T17:28:25.846881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.739192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:25.84692Z","caller":"traceutil/trace.go:171","msg":"trace[980463479] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:635; }","duration":"199.169513ms","start":"2026-05-20T17:28:25.647675Z","end":"2026-05-20T17:28:25.846845Z","steps":["trace[980463479] 'count revisions from in-memory index tree' (duration: 198.61548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:25.846898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.155034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:25.847109Z","caller":"traceutil/trace.go:171","msg":"trace[1761143248] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:635; }","duration":"218.411421ms","start":"2026-05-20T17:28:25.628679Z","end":"2026-05-20T17:28:25.84709Z","steps":["trace[1761143248] 'count revisions from in-memory index tree' (duration: 217.96599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:25.847199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.917726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T17:28:25.847262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.032425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:25.847249Z","caller":"traceutil/trace.go:171","msg":"trace[1189144344] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:635; }","duration":"243.072189ms","start":"2026-05-20T17:28:25.604164Z","end":"2026-05-20T17:28:25.847237Z","steps":["trace[1189144344] 'count revisions from in-memory index tree' (duration: 242.832423ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:25.847314Z","caller":"traceutil/trace.go:171","msg":"trace[1523963521] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:635; }","duration":"242.104836ms","start":"2026-05-20T17:28:25.605198Z","end":"2026-05-20T17:28:25.847302Z","steps":["trace[1523963521] 'range keys from in-memory index tree' (duration: 241.979263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:25.847452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.242518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:25.846995Z","caller":"traceutil/trace.go:171","msg":"trace[553667275] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:635; }","duration":"198.890747ms","start":"2026-05-20T17:28:25.648061Z","end":"2026-05-20T17:28:25.846951Z","steps":["trace[553667275] 'range keys from in-memory index tree' (duration: 198.68488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:25.847461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.566943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:25.847853Z","caller":"traceutil/trace.go:171","msg":"trace[1558331903] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:635; }","duration":"200.105516ms","start":"2026-05-20T17:28:25.64762Z","end":"2026-05-20T17:28:25.847725Z","steps":["trace[1558331903] 'range keys from in-memory index tree' (duration: 199.379519ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:25.848263Z","caller":"traceutil/trace.go:171","msg":"trace[1618224781] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:635; }","duration":"218.609216ms","start":"2026-05-20T17:28:25.628923Z","end":"2026-05-20T17:28:25.847532Z","steps":["trace[1618224781] 'range keys from in-memory index tree' (duration: 217.820627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:25.848401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.828877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:25.848488Z","caller":"traceutil/trace.go:171","msg":"trace[1869505023] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:635; }","duration":"180.96296ms","start":"2026-05-20T17:28:25.667505Z","end":"2026-05-20T17:28:25.848468Z","steps":["trace[1869505023] 'range keys from in-memory index tree' (duration: 180.726993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:25.848745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.081712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:28:25.848811Z","caller":"traceutil/trace.go:171","msg":"trace[2056321484] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:635; }","duration":"181.178904ms","start":"2026-05-20T17:28:25.667623Z","end":"2026-05-20T17:28:25.848801Z","steps":["trace[2056321484] 'count revisions from in-memory index tree' (duration: 181.01507ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:25.848937Z","caller":"traceutil/trace.go:171","msg":"trace[1027991173] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"176.140545ms","start":"2026-05-20T17:28:25.672782Z","end":"2026-05-20T17:28:25.848922Z","steps":["trace[1027991173] 'process raft request' (duration: 175.448189ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:25.851487Z","caller":"traceutil/trace.go:171","msg":"trace[1064902715] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"117.499838ms","start":"2026-05-20T17:28:25.733976Z","end":"2026-05-20T17:28:25.851476Z","steps":["trace[1064902715] 'process raft request' (duration: 117.451087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:39.056278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.243943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T17:28:39.056416Z","caller":"traceutil/trace.go:171","msg":"trace[1802396318] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:765; }","duration":"277.442618ms","start":"2026-05-20T17:28:38.778956Z","end":"2026-05-20T17:28:39.056398Z","steps":["trace[1802396318] 'range keys from in-memory index tree' (duration: 277.074969ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:47.834732Z","caller":"traceutil/trace.go:171","msg":"trace[158571515] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"238.669234ms","start":"2026-05-20T17:28:47.596043Z","end":"2026-05-20T17:28:47.834713Z","steps":["trace[158571515] 'process raft request' (duration: 238.51664ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:47.835771Z","caller":"traceutil/trace.go:171","msg":"trace[953435226] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:881; }","duration":"172.692933ms","start":"2026-05-20T17:28:47.663065Z","end":"2026-05-20T17:28:47.835758Z","steps":["trace[953435226] 'read index received' (duration: 171.496854ms)","trace[953435226] 'applied index is now lower than readState.Index' (duration: 1.195229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:28:47.835998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.245444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T17:28:47.836031Z","caller":"traceutil/trace.go:171","msg":"trace[925449780] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:853; }","duration":"162.302775ms","start":"2026-05-20T17:28:47.673719Z","end":"2026-05-20T17:28:47.836022Z","steps":["trace[925449780] 'agreement among raft nodes before linearized reading' (duration: 162.233574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:47.836151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.021861ms","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-20T17:28:47.836246Z","caller":"traceutil/trace.go:171","msg":"trace[1364889526] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"202.894501ms","start":"2026-05-20T17:28:47.633342Z","end":"2026-05-20T17:28:47.836236Z","steps":["trace[1364889526] 'process raft request' (duration: 202.352489ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:47.836315Z","caller":"traceutil/trace.go:171","msg":"trace[1051515107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:853; }","duration":"173.221145ms","start":"2026-05-20T17:28:47.66304Z","end":"2026-05-20T17:28:47.836261Z","steps":["trace[1051515107] 'agreement among raft nodes before linearized reading' (duration: 172.830736ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:50.60999Z","caller":"traceutil/trace.go:171","msg":"trace[1670540056] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"127.295582ms","start":"2026-05-20T17:28:50.482665Z","end":"2026-05-20T17:28:50.609961Z","steps":["trace[1670540056] 'read index received' (duration: 110.978503ms)","trace[1670540056] 'applied index is now lower than readState.Index' (duration: 16.316179ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:28:50.610381Z","caller":"traceutil/trace.go:171","msg":"trace[694777988] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"129.416902ms","start":"2026-05-20T17:28:50.480947Z","end":"2026-05-20T17:28:50.610364Z","steps":["trace[694777988] 'process raft request' (duration: 112.934489ms)","trace[694777988] 'compare' (duration: 15.963651ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:28:50.610412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.713801ms","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-20T17:28:50.610557Z","caller":"traceutil/trace.go:171","msg":"trace[634889839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"127.906696ms","start":"2026-05-20T17:28:50.482623Z","end":"2026-05-20T17:28:50.610529Z","steps":["trace[634889839] 'agreement among raft nodes before linearized reading' (duration: 127.559148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:28:50.615518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.657749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T17:28:50.615585Z","caller":"traceutil/trace.go:171","msg":"trace[1236896857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"132.791272ms","start":"2026-05-20T17:28:50.482777Z","end":"2026-05-20T17:28:50.615568Z","steps":["trace[1236896857] 'agreement among raft nodes before linearized reading' (duration: 132.625318ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:28:51.878911Z","caller":"traceutil/trace.go:171","msg":"trace[1195838819] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"262.593964ms","start":"2026-05-20T17:28:51.616291Z","end":"2026-05-20T17:28:51.878885Z","steps":["trace[1195838819] 'process raft request' (duration: 262.40658ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:08.893984Z","caller":"traceutil/trace.go:171","msg":"trace[1348801223] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"107.311565ms","start":"2026-05-20T17:29:08.786646Z","end":"2026-05-20T17:29:08.893958Z","steps":["trace[1348801223] 'process raft request' (duration: 52.702234ms)","trace[1348801223] 'compare' (duration: 54.441567ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:29:08.894864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.700778ms","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-20T17:29:08.894996Z","caller":"traceutil/trace.go:171","msg":"trace[1883179755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"100.837592ms","start":"2026-05-20T17:29:08.794117Z","end":"2026-05-20T17:29:08.894955Z","steps":["trace[1883179755] 'agreement among raft nodes before linearized reading' (duration: 99.858779ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:09.072894Z","caller":"traceutil/trace.go:171","msg":"trace[1559295186] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"169.824775ms","start":"2026-05-20T17:29:08.903039Z","end":"2026-05-20T17:29:09.072864Z","steps":["trace[1559295186] 'process raft request' (duration: 169.418795ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:09.073804Z","caller":"traceutil/trace.go:171","msg":"trace[1089960740] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:994; }","duration":"169.553547ms","start":"2026-05-20T17:29:08.904224Z","end":"2026-05-20T17:29:09.073778Z","steps":["trace[1089960740] 'read index received' (duration: 168.212096ms)","trace[1089960740] 'applied index is now lower than readState.Index' (duration: 1.340411ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:29:09.073974Z","caller":"traceutil/trace.go:171","msg":"trace[1448044462] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"169.826863ms","start":"2026-05-20T17:29:08.904129Z","end":"2026-05-20T17:29:09.073956Z","steps":["trace[1448044462] 'process raft request' (duration: 169.524196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:09.074036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.787713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T17:29:09.074069Z","caller":"traceutil/trace.go:171","msg":"trace[682643741] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:960; }","duration":"169.881365ms","start":"2026-05-20T17:29:08.904178Z","end":"2026-05-20T17:29:09.074059Z","steps":["trace[682643741] 'agreement among raft nodes before linearized reading' (duration: 169.699061ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:10.191726Z","caller":"traceutil/trace.go:171","msg":"trace[91669107] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"126.182355ms","start":"2026-05-20T17:29:10.06551Z","end":"2026-05-20T17:29:10.191692Z","steps":["trace[91669107] 'process raft request' (duration: 71.972865ms)","trace[91669107] 'compare' (duration: 54.059657ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:29:12.276428Z","caller":"traceutil/trace.go:171","msg":"trace[1954325750] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"178.769997ms","start":"2026-05-20T17:29:12.097618Z","end":"2026-05-20T17:29:12.276388Z","steps":["trace[1954325750] 'process raft request' (duration: 178.498781ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:12.446804Z","caller":"traceutil/trace.go:171","msg":"trace[1790735527] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"133.475299ms","start":"2026-05-20T17:29:12.313308Z","end":"2026-05-20T17:29:12.446783Z","steps":["trace[1790735527] 'read index received' (duration: 47.247226ms)","trace[1790735527] 'applied index is now lower than readState.Index' (duration: 86.227323ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:29:12.446909Z","caller":"traceutil/trace.go:171","msg":"trace[2044204575] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"167.207602ms","start":"2026-05-20T17:29:12.279664Z","end":"2026-05-20T17:29:12.446871Z","steps":["trace[2044204575] 'process raft request' (duration: 80.914077ms)","trace[2044204575] 'compare' (duration: 86.136632ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:29:12.44696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.632853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"info","ts":"2026-05-20T17:29:12.446988Z","caller":"traceutil/trace.go:171","msg":"trace[1767389229] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:972; }","duration":"133.707015ms","start":"2026-05-20T17:29:12.313273Z","end":"2026-05-20T17:29:12.44698Z","steps":["trace[1767389229] 'agreement among raft nodes before linearized reading' (duration: 133.583952ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:12.868646Z","caller":"traceutil/trace.go:171","msg":"trace[884218418] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"120.381297ms","start":"2026-05-20T17:29:12.748188Z","end":"2026-05-20T17:29:12.86857Z","steps":["trace[884218418] 'process raft request' (duration: 39.597903ms)","trace[884218418] 'compare' (duration: 80.165098ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:29:14.404715Z","caller":"traceutil/trace.go:171","msg":"trace[1984203834] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"307.638187ms","start":"2026-05-20T17:29:14.097053Z","end":"2026-05-20T17:29:14.404692Z","steps":["trace[1984203834] 'process raft request' (duration: 307.436202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:14.404894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:14.097012Z","time spent":"307.796531ms","remote":"127.0.0.1:44176","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-20T17:29:15.283297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.372313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-20T17:29:15.28333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.582353ms","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-20T17:29:15.283369Z","caller":"traceutil/trace.go:171","msg":"trace[413210776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"187.500476ms","start":"2026-05-20T17:29:15.095849Z","end":"2026-05-20T17:29:15.28335Z","steps":["trace[413210776] 'range keys from in-memory index tree' (duration: 187.211768ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:15.283393Z","caller":"traceutil/trace.go:171","msg":"trace[439204264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"186.698507ms","start":"2026-05-20T17:29:15.096678Z","end":"2026-05-20T17:29:15.283377Z","steps":["trace[439204264] 'range keys from in-memory index tree' (duration: 186.444151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:15.283376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.509991ms","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":"warn","ts":"2026-05-20T17:29:15.283412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.006142ms","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-20T17:29:15.28344Z","caller":"traceutil/trace.go:171","msg":"trace[1874840437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"833.621254ms","start":"2026-05-20T17:29:14.449803Z","end":"2026-05-20T17:29:15.283424Z","steps":["trace[1874840437] 'range keys from in-memory index tree' (duration: 833.362088ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:15.283458Z","caller":"traceutil/trace.go:171","msg":"trace[124590156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"535.083863ms","start":"2026-05-20T17:29:14.74836Z","end":"2026-05-20T17:29:15.283444Z","steps":["trace[124590156] 'range keys from in-memory index tree' (duration: 534.862119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:15.283475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:14.449783Z","time spent":"833.684045ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T17:29:15.283496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:14.748333Z","time spent":"535.152405ms","remote":"127.0.0.1:44176","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-20T17:29:15.283565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.22292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T17:29:15.283607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.151082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T17:29:15.283616Z","caller":"traceutil/trace.go:171","msg":"trace[1422531574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"803.303172ms","start":"2026-05-20T17:29:14.480299Z","end":"2026-05-20T17:29:15.283602Z","steps":["trace[1422531574] 'range keys from in-memory index tree' (duration: 803.147057ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:15.283629Z","caller":"traceutil/trace.go:171","msg":"trace[1045545908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:978; }","duration":"411.199673ms","start":"2026-05-20T17:29:14.872422Z","end":"2026-05-20T17:29:15.283621Z","steps":["trace[1045545908] 'range keys from in-memory index tree' (duration: 411.06405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:15.283648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:14.872403Z","time spent":"411.240474ms","remote":"127.0.0.1:44176","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-20T17:29:15.283648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:14.480287Z","time spent":"803.353723ms","remote":"127.0.0.1:43882","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-20T17:29:15.861983Z","caller":"traceutil/trace.go:171","msg":"trace[1016622826] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"573.423595ms","start":"2026-05-20T17:29:15.288532Z","end":"2026-05-20T17:29:15.861955Z","steps":["trace[1016622826] 'process raft request' (duration: 573.22899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:15.862157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:15.2885Z","time spent":"573.566579ms","remote":"127.0.0.1:44176","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-20T17:29:16.593025Z","caller":"traceutil/trace.go:171","msg":"trace[1899098818] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.30422446s","start":"2026-05-20T17:29:15.288672Z","end":"2026-05-20T17:29:16.592897Z","steps":["trace[1899098818] 'process raft request' (duration: 1.30382206s)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:16.593235Z","caller":"traceutil/trace.go:171","msg":"trace[575930742] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.304366473s","start":"2026-05-20T17:29:15.28883Z","end":"2026-05-20T17:29:16.593197Z","steps":["trace[575930742] 'process raft request' (duration: 1.304006845s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:16.593365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:15.288652Z","time spent":"1.304504647s","remote":"127.0.0.1:44176","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-20T17:29:16.593401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:15.288822Z","time spent":"1.304486487s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:29:16.593498Z","caller":"traceutil/trace.go:171","msg":"trace[1301697064] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.304615499s","start":"2026-05-20T17:29:15.288873Z","end":"2026-05-20T17:29:16.593489Z","steps":["trace[1301697064] 'process raft request' (duration: 1.30423891s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:16.59356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:15.28886Z","time spent":"1.304668541s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:29:16.593661Z","caller":"traceutil/trace.go:171","msg":"trace[1843257466] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1015; }","duration":"1.112105766s","start":"2026-05-20T17:29:15.481543Z","end":"2026-05-20T17:29:16.593649Z","steps":["trace[1843257466] 'read index received' (duration: 380.192575ms)","trace[1843257466] 'applied index is now lower than readState.Index' (duration: 731.912111ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:29:16.593749Z","caller":"traceutil/trace.go:171","msg":"trace[189399797] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.304692901s","start":"2026-05-20T17:29:15.289046Z","end":"2026-05-20T17:29:16.593739Z","steps":["trace[189399797] 'process raft request' (duration: 1.304396564s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:16.593802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:15.289037Z","time spent":"1.304733512s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:29:16.593863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112325561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:29:16.59393Z","caller":"traceutil/trace.go:171","msg":"trace[1307968126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"1.112494546s","start":"2026-05-20T17:29:15.481417Z","end":"2026-05-20T17:29:16.593912Z","steps":["trace[1307968126] 'agreement among raft nodes before linearized reading' (duration: 1.112380392s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:16.593975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:15.481376Z","time spent":"1.112589018s","remote":"127.0.0.1:43882","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-20T17:29:16.595567Z","caller":"traceutil/trace.go:171","msg":"trace[923563348] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"186.248705ms","start":"2026-05-20T17:29:16.409292Z","end":"2026-05-20T17:29:16.595541Z","steps":["trace[923563348] 'process raft request' (duration: 185.767154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:39.105337Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.052694378s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T17:29:39.105738Z","caller":"traceutil/trace.go:171","msg":"trace[291371801] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"1.053197501s","start":"2026-05-20T17:29:38.052515Z","end":"2026-05-20T17:29:39.105712Z","steps":["trace[291371801] 'process raft request' (duration: 1.053057016s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:39.105887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:38.052489Z","time spent":"1.053322414s","remote":"127.0.0.1:44176","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-20T17:29:39.120715Z","caller":"traceutil/trace.go:171","msg":"trace[61959840] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"430.996102ms","start":"2026-05-20T17:29:38.689702Z","end":"2026-05-20T17:29:39.120698Z","steps":["trace[61959840] 'read index received' (duration: 416.002299ms)","trace[61959840] 'applied index is now lower than readState.Index' (duration: 14.992803ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:29:39.120805Z","caller":"traceutil/trace.go:171","msg":"trace[24894585] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"439.830997ms","start":"2026-05-20T17:29:38.680965Z","end":"2026-05-20T17:29:39.120796Z","steps":["trace[24894585] 'process raft request' (duration: 439.637092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:39.120886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:38.680938Z","time spent":"439.902039ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:29:39.120982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.025008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T17:29:39.121048Z","caller":"traceutil/trace.go:171","msg":"trace[110066967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1052; }","duration":"381.158231ms","start":"2026-05-20T17:29:38.739872Z","end":"2026-05-20T17:29:39.12103Z","steps":["trace[110066967] 'agreement among raft nodes before linearized reading' (duration: 380.979007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:39.121085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:38.73985Z","time spent":"381.227532ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T17:29:39.121135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.456384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-20T17:29:39.121244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.541125ms","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-20T17:29:39.12125Z","caller":"traceutil/trace.go:171","msg":"trace[621774562] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1052; }","duration":"338.627238ms","start":"2026-05-20T17:29:38.782607Z","end":"2026-05-20T17:29:39.121234Z","steps":["trace[621774562] 'agreement among raft nodes before linearized reading' (duration: 338.309711ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:39.121284Z","caller":"traceutil/trace.go:171","msg":"trace[998217660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"431.613957ms","start":"2026-05-20T17:29:38.689656Z","end":"2026-05-20T17:29:39.12127Z","steps":["trace[998217660] 'agreement among raft nodes before linearized reading' (duration: 431.267899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:39.121293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:38.782589Z","time spent":"338.694989ms","remote":"127.0.0.1:43910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-05-20T17:29:39.121287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.377778ms","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-20T17:29:39.121316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:38.689634Z","time spent":"431.674748ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T17:29:39.121127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.025969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T17:29:39.121364Z","caller":"traceutil/trace.go:171","msg":"trace[223088519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"379.318056ms","start":"2026-05-20T17:29:38.742035Z","end":"2026-05-20T17:29:39.121353Z","steps":["trace[223088519] 'agreement among raft nodes before linearized reading' (duration: 378.957307ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:39.121367Z","caller":"traceutil/trace.go:171","msg":"trace[1442823578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1052; }","duration":"372.505711ms","start":"2026-05-20T17:29:38.748835Z","end":"2026-05-20T17:29:39.121341Z","steps":["trace[1442823578] 'agreement among raft nodes before linearized reading' (duration: 372.133252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:39.121406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:38.742013Z","time spent":"379.379747ms","remote":"127.0.0.1:44176","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-20T17:29:39.121424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:38.748813Z","time spent":"372.598473ms","remote":"127.0.0.1:44176","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-20T17:29:41.616155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.217642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:29:41.616258Z","caller":"traceutil/trace.go:171","msg":"trace[1071937304] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"387.065154ms","start":"2026-05-20T17:29:41.229177Z","end":"2026-05-20T17:29:41.616242Z","steps":["trace[1071937304] 'read index received' (duration: 259.618997ms)","trace[1071937304] 'applied index is now lower than readState.Index' (duration: 127.444747ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:29:41.616383Z","caller":"traceutil/trace.go:171","msg":"trace[1571136022] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"490.15642ms","start":"2026-05-20T17:29:41.126176Z","end":"2026-05-20T17:29:41.616333Z","steps":["trace[1571136022] 'process raft request' (duration: 362.672641ms)","trace[1571136022] 'compare' (duration: 127.11314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:29:41.616426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.26396ms","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-20T17:29:41.616451Z","caller":"traceutil/trace.go:171","msg":"trace[1427359143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"387.324072ms","start":"2026-05-20T17:29:41.229118Z","end":"2026-05-20T17:29:41.616442Z","steps":["trace[1427359143] 'agreement among raft nodes before linearized reading' (duration: 387.205409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.61648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:41.229094Z","time spent":"387.378953ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T17:29:41.61649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:41.126159Z","time spent":"490.278303ms","remote":"127.0.0.1:44176","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-20T17:29:41.616783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.678653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:29:41.616849Z","caller":"traceutil/trace.go:171","msg":"trace[1192805472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"134.770454ms","start":"2026-05-20T17:29:41.482058Z","end":"2026-05-20T17:29:41.616829Z","steps":["trace[1192805472] 'agreement among raft nodes before linearized reading' (duration: 134.683662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.616907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.84584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T17:29:41.617054Z","caller":"traceutil/trace.go:171","msg":"trace[2114054330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1060; }","duration":"386.987603ms","start":"2026-05-20T17:29:41.230004Z","end":"2026-05-20T17:29:41.616991Z","steps":["trace[2114054330] 'agreement among raft nodes before linearized reading' (duration: 386.724707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.61707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.995193ms","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-20T17:29:41.617148Z","caller":"traceutil/trace.go:171","msg":"trace[230655133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1060; }","duration":"387.084686ms","start":"2026-05-20T17:29:41.230041Z","end":"2026-05-20T17:29:41.617126Z","steps":["trace[230655133] 'agreement among raft nodes before linearized reading' (duration: 386.84234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.617131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:41.229994Z","time spent":"387.103766ms","remote":"127.0.0.1:44176","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-20T17:29:41.617204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:41.230032Z","time spent":"387.164577ms","remote":"127.0.0.1:44176","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-20T17:29:41.617039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.965288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:29:41.617299Z","caller":"traceutil/trace.go:171","msg":"trace[1768115547] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1060; }","duration":"123.359917ms","start":"2026-05-20T17:29:41.49392Z","end":"2026-05-20T17:29:41.617279Z","steps":["trace[1768115547] 'agreement among raft nodes before linearized reading' (duration: 122.914917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.617504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.615934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T17:29:41.617592Z","caller":"traceutil/trace.go:171","msg":"trace[1943176809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1060; }","duration":"386.730877ms","start":"2026-05-20T17:29:41.23084Z","end":"2026-05-20T17:29:41.617571Z","steps":["trace[1943176809] 'agreement among raft nodes before linearized reading' (duration: 385.921138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.617636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:41.230814Z","time spent":"386.812979ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-20T17:29:41.969662Z","caller":"traceutil/trace.go:171","msg":"trace[1774247017] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"343.004235ms","start":"2026-05-20T17:29:41.626621Z","end":"2026-05-20T17:29:41.969625Z","steps":["trace[1774247017] 'process raft request' (duration: 342.677677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.969926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:41.62659Z","time spent":"343.148979ms","remote":"127.0.0.1:44176","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-20T17:29:41.970217Z","caller":"traceutil/trace.go:171","msg":"trace[382292917] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"343.199949ms","start":"2026-05-20T17:29:41.626619Z","end":"2026-05-20T17:29:41.969819Z","steps":["trace[382292917] 'process raft request' (duration: 342.867722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.970815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:41.6266Z","time spent":"343.703162ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:29:41.974669Z","caller":"traceutil/trace.go:171","msg":"trace[956996673] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"347.789592ms","start":"2026-05-20T17:29:41.626859Z","end":"2026-05-20T17:29:41.974648Z","steps":["trace[956996673] 'process raft request' (duration: 347.655049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.974789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:41.626847Z","time spent":"347.882704ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:29:41.974866Z","caller":"traceutil/trace.go:171","msg":"trace[619000930] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"347.838062ms","start":"2026-05-20T17:29:41.626968Z","end":"2026-05-20T17:29:41.974806Z","steps":["trace[619000930] 'process raft request' (duration: 347.630107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:41.975248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:29:41.626946Z","time spent":"348.228452ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:29:43.809029Z","caller":"traceutil/trace.go:171","msg":"trace[198928988] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"179.121342ms","start":"2026-05-20T17:29:43.629883Z","end":"2026-05-20T17:29:43.809004Z","steps":["trace[198928988] 'process raft request' (duration: 178.925938ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:44.169975Z","caller":"traceutil/trace.go:171","msg":"trace[1538023608] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"262.906688ms","start":"2026-05-20T17:29:43.907029Z","end":"2026-05-20T17:29:44.169936Z","steps":["trace[1538023608] 'read index received' (duration: 169.158861ms)","trace[1538023608] 'applied index is now lower than readState.Index' (duration: 93.745947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:29:44.170239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.172374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:29:44.170273Z","caller":"traceutil/trace.go:171","msg":"trace[1155291684] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1067; }","duration":"263.254426ms","start":"2026-05-20T17:29:43.907011Z","end":"2026-05-20T17:29:44.170265Z","steps":["trace[1155291684] 'agreement among raft nodes before linearized reading' (duration: 263.126413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:44.170366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.143184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-20T17:29:44.170374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.52375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-20T17:29:44.170572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.796382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:29:44.170687Z","caller":"traceutil/trace.go:171","msg":"trace[363413383] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1067; }","duration":"152.963087ms","start":"2026-05-20T17:29:44.017689Z","end":"2026-05-20T17:29:44.170652Z","steps":["trace[363413383] 'agreement among raft nodes before linearized reading' (duration: 152.677939ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:44.17069Z","caller":"traceutil/trace.go:171","msg":"trace[1421909464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"195.853509ms","start":"2026-05-20T17:29:43.974792Z","end":"2026-05-20T17:29:44.170646Z","steps":["trace[1421909464] 'agreement among raft nodes before linearized reading' (duration: 195.388637ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:44.170626Z","caller":"traceutil/trace.go:171","msg":"trace[770786611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1067; }","duration":"191.432942ms","start":"2026-05-20T17:29:43.979161Z","end":"2026-05-20T17:29:44.170594Z","steps":["trace[770786611] 'agreement among raft nodes before linearized reading' (duration: 191.047702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:44.170607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.900825ms","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-20T17:29:44.170845Z","caller":"traceutil/trace.go:171","msg":"trace[864303312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"195.234654ms","start":"2026-05-20T17:29:43.975599Z","end":"2026-05-20T17:29:44.170834Z","steps":["trace[864303312] 'agreement among raft nodes before linearized reading' (duration: 194.879804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:29:44.171519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.352134ms","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-20T17:29:44.171559Z","caller":"traceutil/trace.go:171","msg":"trace[1115734632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"192.415426ms","start":"2026-05-20T17:29:43.979121Z","end":"2026-05-20T17:29:44.171551Z","steps":["trace[1115734632] 'agreement among raft nodes before linearized reading' (duration: 191.36029ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:29:56.866785Z","caller":"traceutil/trace.go:171","msg":"trace[816513656] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1110; }","duration":"150.123318ms","start":"2026-05-20T17:29:56.716631Z","end":"2026-05-20T17:29:56.866754Z","steps":["trace[816513656] 'process raft request' (duration: 149.927313ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:02.016557Z","caller":"traceutil/trace.go:171","msg":"trace[1219307658] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"122.380534ms","start":"2026-05-20T17:30:01.894149Z","end":"2026-05-20T17:30:02.01653Z","steps":["trace[1219307658] 'process raft request' (duration: 122.245541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:02.461683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.783952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T17:30:02.46176Z","caller":"traceutil/trace.go:171","msg":"trace[1015715691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1177; }","duration":"159.903045ms","start":"2026-05-20T17:30:02.30184Z","end":"2026-05-20T17:30:02.461743Z","steps":["trace[1015715691] 'range keys from in-memory index tree' (duration: 159.549317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:02.461981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.140082ms","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-20T17:30:02.462039Z","caller":"traceutil/trace.go:171","msg":"trace[877969408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1177; }","duration":"195.240865ms","start":"2026-05-20T17:30:02.26678Z","end":"2026-05-20T17:30:02.462021Z","steps":["trace[877969408] 'range keys from in-memory index tree' (duration: 195.010259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:02.46206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.843267ms","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-20T17:30:02.462097Z","caller":"traceutil/trace.go:171","msg":"trace[31273070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1177; }","duration":"160.918709ms","start":"2026-05-20T17:30:02.301168Z","end":"2026-05-20T17:30:02.462087Z","steps":["trace[31273070] 'range keys from in-memory index tree' (duration: 160.730105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:02.46214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.167042ms","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-20T17:30:02.462209Z","caller":"traceutil/trace.go:171","msg":"trace[1085084321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1177; }","duration":"160.266654ms","start":"2026-05-20T17:30:02.301925Z","end":"2026-05-20T17:30:02.462192Z","steps":["trace[1085084321] 'range keys from in-memory index tree' (duration: 160.030229ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:03.920335Z","caller":"traceutil/trace.go:171","msg":"trace[9477916] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"442.361418ms","start":"2026-05-20T17:30:03.477956Z","end":"2026-05-20T17:30:03.920318Z","steps":["trace[9477916] 'read index received' (duration: 442.192373ms)","trace[9477916] 'applied index is now lower than readState.Index' (duration: 168.575µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:30:03.920426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.46272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:30:03.920446Z","caller":"traceutil/trace.go:171","msg":"trace[601286406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1183; }","duration":"442.536522ms","start":"2026-05-20T17:30:03.477901Z","end":"2026-05-20T17:30:03.920438Z","steps":["trace[601286406] 'agreement among raft nodes before linearized reading' (duration: 442.487101ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:03.920434Z","caller":"traceutil/trace.go:171","msg":"trace[225539788] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"588.286263ms","start":"2026-05-20T17:30:03.332115Z","end":"2026-05-20T17:30:03.920401Z","steps":["trace[225539788] 'process raft request' (duration: 588.080957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:03.920467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:03.47788Z","time spent":"442.581613ms","remote":"127.0.0.1:43882","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-20T17:30:03.920585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:03.332095Z","time spent":"588.405725ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:30:04.425582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872867711805692,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T17:30:04.540407Z","caller":"traceutil/trace.go:171","msg":"trace[532380217] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1231; }","duration":"615.801403ms","start":"2026-05-20T17:30:03.924589Z","end":"2026-05-20T17:30:04.540391Z","steps":["trace[532380217] 'read index received' (duration: 582.831332ms)","trace[532380217] 'applied index is now lower than readState.Index' (duration: 32.967201ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:30:04.54051Z","caller":"traceutil/trace.go:171","msg":"trace[2008956826] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"518.476798ms","start":"2026-05-20T17:30:04.021989Z","end":"2026-05-20T17:30:04.540466Z","steps":["trace[2008956826] 'process raft request' (duration: 518.315154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:04.540723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:04.021972Z","time spent":"518.648632ms","remote":"127.0.0.1:44176","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-20T17:30:04.541513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.90669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:30:04.541541Z","caller":"traceutil/trace.go:171","msg":"trace[397438892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1184; }","duration":"616.979391ms","start":"2026-05-20T17:30:03.924555Z","end":"2026-05-20T17:30:04.541534Z","steps":["trace[397438892] 'agreement among raft nodes before linearized reading' (duration: 615.925226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:04.541561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:03.924532Z","time spent":"617.025312ms","remote":"127.0.0.1:43894","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-20T17:30:16.119072Z","caller":"traceutil/trace.go:171","msg":"trace[1101791699] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"145.811013ms","start":"2026-05-20T17:30:15.973237Z","end":"2026-05-20T17:30:16.119048Z","steps":["trace[1101791699] 'process raft request' (duration: 145.634868ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:20.738646Z","caller":"traceutil/trace.go:171","msg":"trace[369547192] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"103.599887ms","start":"2026-05-20T17:30:20.635006Z","end":"2026-05-20T17:30:20.738605Z","steps":["trace[369547192] 'process raft request' (duration: 103.332681ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:20.942703Z","caller":"traceutil/trace.go:171","msg":"trace[1469459655] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"199.578978ms","start":"2026-05-20T17:30:20.743096Z","end":"2026-05-20T17:30:20.942675Z","steps":["trace[1469459655] 'process raft request' (duration: 199.470826ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:20.946311Z","caller":"traceutil/trace.go:171","msg":"trace[609362917] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"199.822045ms","start":"2026-05-20T17:30:20.746477Z","end":"2026-05-20T17:30:20.946299Z","steps":["trace[609362917] 'process raft request' (duration: 199.798205ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:20.946526Z","caller":"traceutil/trace.go:171","msg":"trace[116412648] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"202.141201ms","start":"2026-05-20T17:30:20.744353Z","end":"2026-05-20T17:30:20.946495Z","steps":["trace[116412648] 'process raft request' (duration: 201.816663ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:20.94677Z","caller":"traceutil/trace.go:171","msg":"trace[1306908232] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"201.547907ms","start":"2026-05-20T17:30:20.745204Z","end":"2026-05-20T17:30:20.946752Z","steps":["trace[1306908232] 'process raft request' (duration: 201.048435ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:24.280326Z","caller":"traceutil/trace.go:171","msg":"trace[1546905061] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"135.126858ms","start":"2026-05-20T17:30:24.145175Z","end":"2026-05-20T17:30:24.280302Z","steps":["trace[1546905061] 'read index received' (duration: 100.939931ms)","trace[1546905061] 'applied index is now lower than readState.Index' (duration: 34.185477ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:30:24.28058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.380944ms","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-20T17:30:24.280653Z","caller":"traceutil/trace.go:171","msg":"trace[741221964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1283; }","duration":"135.508567ms","start":"2026-05-20T17:30:24.145129Z","end":"2026-05-20T17:30:24.280637Z","steps":["trace[741221964] 'agreement among raft nodes before linearized reading' (duration: 135.274991ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:25.112872Z","caller":"traceutil/trace.go:171","msg":"trace[1845920330] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"157.344862ms","start":"2026-05-20T17:30:24.95551Z","end":"2026-05-20T17:30:25.112855Z","steps":["trace[1845920330] 'read index received' (duration: 157.184077ms)","trace[1845920330] 'applied index is now lower than readState.Index' (duration: 160.245µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:30:25.11295Z","caller":"traceutil/trace.go:171","msg":"trace[884508808] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"358.71593ms","start":"2026-05-20T17:30:24.754228Z","end":"2026-05-20T17:30:25.112944Z","steps":["trace[884508808] 'process raft request' (duration: 358.522426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:25.113035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:24.754208Z","time spent":"358.758861ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:30:25.113178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.677761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T17:30:25.113201Z","caller":"traceutil/trace.go:171","msg":"trace[693954552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"157.739302ms","start":"2026-05-20T17:30:24.955455Z","end":"2026-05-20T17:30:25.113195Z","steps":["trace[693954552] 'agreement among raft nodes before linearized reading' (duration: 157.65562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:25.113419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.940759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-20T17:30:25.113486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.884767ms","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-20T17:30:25.113508Z","caller":"traceutil/trace.go:171","msg":"trace[234891651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"151.914339ms","start":"2026-05-20T17:30:24.961586Z","end":"2026-05-20T17:30:25.1135Z","steps":["trace[234891651] 'agreement among raft nodes before linearized reading' (duration: 151.863177ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:25.113489Z","caller":"traceutil/trace.go:171","msg":"trace[1403374859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1286; }","duration":"152.017351ms","start":"2026-05-20T17:30:24.961455Z","end":"2026-05-20T17:30:25.113473Z","steps":["trace[1403374859] 'agreement among raft nodes before linearized reading' (duration: 151.847437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:25.11379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.185885ms","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-20T17:30:25.113872Z","caller":"traceutil/trace.go:171","msg":"trace[874872776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1286; }","duration":"152.288857ms","start":"2026-05-20T17:30:24.961562Z","end":"2026-05-20T17:30:25.113851Z","steps":["trace[874872776] 'agreement among raft nodes before linearized reading' (duration: 152.020571ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:25.544922Z","caller":"traceutil/trace.go:171","msg":"trace[93847409] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"428.434427ms","start":"2026-05-20T17:30:25.116456Z","end":"2026-05-20T17:30:25.54489Z","steps":["trace[93847409] 'process raft request' (duration: 354.721972ms)","trace[93847409] 'compare' (duration: 73.173272ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:30:25.545054Z","caller":"traceutil/trace.go:171","msg":"trace[663823970] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"428.213892ms","start":"2026-05-20T17:30:25.116821Z","end":"2026-05-20T17:30:25.545035Z","steps":["trace[663823970] 'process raft request' (duration: 427.873594ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:25.545064Z","caller":"traceutil/trace.go:171","msg":"trace[1975924853] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"426.49095ms","start":"2026-05-20T17:30:25.11855Z","end":"2026-05-20T17:30:25.545041Z","steps":["trace[1975924853] 'process raft request' (duration: 426.212403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:25.545079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:25.116435Z","time spent":"428.561711ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:30:25.545071Z","caller":"traceutil/trace.go:171","msg":"trace[1074479329] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"427.72834ms","start":"2026-05-20T17:30:25.117277Z","end":"2026-05-20T17:30:25.545006Z","steps":["trace[1074479329] 'process raft request' (duration: 427.454763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:25.545721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:25.118541Z","time spent":"426.797567ms","remote":"127.0.0.1:44176","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-20T17:30:25.545763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:25.117257Z","time spent":"428.353366ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:30:25.545168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:25.116796Z","time spent":"428.303074ms","remote":"127.0.0.1:44176","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-20T17:30:25.881825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.936328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:30:25.882081Z","caller":"traceutil/trace.go:171","msg":"trace[435190089] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"159.952745ms","start":"2026-05-20T17:30:25.722102Z","end":"2026-05-20T17:30:25.882055Z","steps":["trace[435190089] 'process raft request' (duration: 159.879593ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:25.882187Z","caller":"traceutil/trace.go:171","msg":"trace[216809286] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"160.278603ms","start":"2026-05-20T17:30:25.721845Z","end":"2026-05-20T17:30:25.882124Z","steps":["trace[216809286] 'process raft request' (duration: 43.933295ms)","trace[216809286] 'compare' (duration: 115.730383ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:30:26.384011Z","caller":"traceutil/trace.go:171","msg":"trace[1936425869] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"289.874505ms","start":"2026-05-20T17:30:26.094108Z","end":"2026-05-20T17:30:26.383983Z","steps":["trace[1936425869] 'read index received' (duration: 289.554327ms)","trace[1936425869] 'applied index is now lower than readState.Index' (duration: 318.948µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:30:26.384093Z","caller":"traceutil/trace.go:171","msg":"trace[87682488] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"492.678939ms","start":"2026-05-20T17:30:25.891365Z","end":"2026-05-20T17:30:26.384044Z","steps":["trace[87682488] 'process raft request' (duration: 492.370512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:26.38424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.231565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T17:30:26.384287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.177372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-20T17:30:26.384397Z","caller":"traceutil/trace.go:171","msg":"trace[671616567] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1296; }","duration":"290.329206ms","start":"2026-05-20T17:30:26.094054Z","end":"2026-05-20T17:30:26.384383Z","steps":["trace[671616567] 'agreement among raft nodes before linearized reading' (duration: 290.10114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:26.38438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:25.891351Z","time spent":"492.821023ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:30:26.384303Z","caller":"traceutil/trace.go:171","msg":"trace[786197892] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1296; }","duration":"163.337337ms","start":"2026-05-20T17:30:26.220952Z","end":"2026-05-20T17:30:26.384289Z","steps":["trace[786197892] 'agreement among raft nodes before linearized reading' (duration: 163.224155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:26.813845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.39293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:30:26.813965Z","caller":"traceutil/trace.go:171","msg":"trace[532483609] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"424.785168ms","start":"2026-05-20T17:30:26.389159Z","end":"2026-05-20T17:30:26.813945Z","steps":["trace[532483609] 'process raft request' (duration: 185.203614ms)","trace[532483609] 'compare' (duration: 238.99288ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:30:26.814024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:26.389133Z","time spent":"424.8641ms","remote":"127.0.0.1:44176","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-20T17:30:26.897381Z","caller":"traceutil/trace.go:171","msg":"trace[1921275360] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1349; }","duration":"416.303581ms","start":"2026-05-20T17:30:26.481054Z","end":"2026-05-20T17:30:26.897358Z","steps":["trace[1921275360] 'read index received' (duration: 93.595422ms)","trace[1921275360] 'applied index is now lower than readState.Index' (duration: 322.707099ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:30:26.897557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.489246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:30:26.897621Z","caller":"traceutil/trace.go:171","msg":"trace[519642453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"416.602148ms","start":"2026-05-20T17:30:26.481004Z","end":"2026-05-20T17:30:26.897606Z","steps":["trace[519642453] 'agreement among raft nodes before linearized reading' (duration: 416.487336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:26.89766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:26.480982Z","time spent":"416.6707ms","remote":"127.0.0.1:43882","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-20T17:30:26.897819Z","caller":"traceutil/trace.go:171","msg":"trace[1672509914] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"507.918104ms","start":"2026-05-20T17:30:26.389704Z","end":"2026-05-20T17:30:26.897622Z","steps":["trace[1672509914] 'process raft request' (duration: 507.516304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:26.898033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:26.389689Z","time spent":"508.19495ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:30:29.13064Z","caller":"traceutil/trace.go:171","msg":"trace[1544853256] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"154.82184ms","start":"2026-05-20T17:30:28.975788Z","end":"2026-05-20T17:30:29.13061Z","steps":["trace[1544853256] 'process raft request' (duration: 154.597125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:29.525029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.537178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:30:29.525159Z","caller":"traceutil/trace.go:171","msg":"trace[1227353571] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"386.750097ms","start":"2026-05-20T17:30:29.138384Z","end":"2026-05-20T17:30:29.525134Z","steps":["trace[1227353571] 'process raft request' (duration: 56.998235ms)","trace[1227353571] 'compare' (duration: 329.418883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:30:29.525221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:29.138355Z","time spent":"386.83692ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:30:30.94033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164697821s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:30:30.940597Z","caller":"traceutil/trace.go:171","msg":"trace[2137150019] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1376; }","duration":"1.45919626s","start":"2026-05-20T17:30:29.481383Z","end":"2026-05-20T17:30:30.940579Z","steps":["trace[2137150019] 'read index received' (duration: 294.087629ms)","trace[2137150019] 'applied index is now lower than readState.Index' (duration: 1.165107831s)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:30:30.940606Z","caller":"traceutil/trace.go:171","msg":"trace[1647211222] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"1.801132241s","start":"2026-05-20T17:30:29.139447Z","end":"2026-05-20T17:30:30.94058Z","steps":["trace[1647211222] 'process raft request' (duration: 636.097992ms)","trace[1647211222] 'compare' (duration: 1.164292602s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:30:30.940714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:29.139428Z","time spent":"1.801244594s","remote":"127.0.0.1:44066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:30:30.940815Z","caller":"traceutil/trace.go:171","msg":"trace[1395746420] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"1.799272906s","start":"2026-05-20T17:30:29.141531Z","end":"2026-05-20T17:30:30.940804Z","steps":["trace[1395746420] 'process raft request' (duration: 1.798897677s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:30.940884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:29.14151Z","time spent":"1.799340157s","remote":"127.0.0.1:44398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10921,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:30:30.94093Z","caller":"traceutil/trace.go:171","msg":"trace[1424437099] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"1.768462341s","start":"2026-05-20T17:30:29.172445Z","end":"2026-05-20T17:30:30.940907Z","steps":["trace[1424437099] 'process raft request' (duration: 1.768083522s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:30.941078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:29.172421Z","time spent":"1.768575934s","remote":"127.0.0.1:44176","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-20T17:30:30.941047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459627021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:30:30.941151Z","caller":"traceutil/trace.go:171","msg":"trace[1443980375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"1.459788975s","start":"2026-05-20T17:30:29.481336Z","end":"2026-05-20T17:30:30.941125Z","steps":["trace[1443980375] 'agreement among raft nodes before linearized reading' (duration: 1.45961251s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:30.941214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:29.481317Z","time spent":"1.459887656s","remote":"127.0.0.1:43882","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-20T17:30:31.015352Z","caller":"traceutil/trace.go:171","msg":"trace[1537193746] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"730.353999ms","start":"2026-05-20T17:30:30.28479Z","end":"2026-05-20T17:30:31.015144Z","steps":["trace[1537193746] 'process raft request' (duration: 730.223315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:31.015488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:30.284764Z","time spent":"730.650587ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:30:31.01555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.466466ms","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-20T17:30:31.015634Z","caller":"traceutil/trace.go:171","msg":"trace[516657901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1326; }","duration":"155.60542ms","start":"2026-05-20T17:30:30.860008Z","end":"2026-05-20T17:30:31.015614Z","steps":["trace[516657901] 'agreement among raft nodes before linearized reading' (duration: 155.346294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:31.015812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445273761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T17:30:31.015841Z","caller":"traceutil/trace.go:171","msg":"trace[1878093717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1326; }","duration":"1.445323001s","start":"2026-05-20T17:30:29.57051Z","end":"2026-05-20T17:30:31.015833Z","steps":["trace[1878093717] 'agreement among raft nodes before linearized reading' (duration: 1.445222889s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:31.015861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:29.570419Z","time spent":"1.445438244s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T17:30:31.015934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44609464s","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-20T17:30:31.015977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.096409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-20T17:30:31.015997Z","caller":"traceutil/trace.go:171","msg":"trace[101329326] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1326; }","duration":"479.15783ms","start":"2026-05-20T17:30:30.536833Z","end":"2026-05-20T17:30:31.015991Z","steps":["trace[101329326] 'agreement among raft nodes before linearized reading' (duration: 479.105989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:31.016017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:30.536812Z","time spent":"479.201492ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-20T17:30:31.016045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449208167s","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-20T17:30:31.016074Z","caller":"traceutil/trace.go:171","msg":"trace[1865412999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"1.449268489s","start":"2026-05-20T17:30:29.566797Z","end":"2026-05-20T17:30:31.016066Z","steps":["trace[1865412999] 'agreement among raft nodes before linearized reading' (duration: 1.449193717s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:31.016103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:29.56677Z","time spent":"1.449322199s","remote":"127.0.0.1:44176","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-20T17:30:31.016133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445155667s","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-20T17:30:31.016183Z","caller":"traceutil/trace.go:171","msg":"trace[681968418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1326; }","duration":"1.445193948s","start":"2026-05-20T17:30:29.570972Z","end":"2026-05-20T17:30:31.016166Z","steps":["trace[681968418] 'agreement among raft nodes before linearized reading' (duration: 1.445118366s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:31.0162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:29.57092Z","time spent":"1.44527652s","remote":"127.0.0.1:44176","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-20T17:30:31.01597Z","caller":"traceutil/trace.go:171","msg":"trace[1282349179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1326; }","duration":"1.446150961s","start":"2026-05-20T17:30:29.569805Z","end":"2026-05-20T17:30:31.015956Z","steps":["trace[1282349179] 'agreement among raft nodes before linearized reading' (duration: 1.446055539s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:31.016268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:30:29.569792Z","time spent":"1.446469589s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-20T17:30:31.223016Z","caller":"traceutil/trace.go:171","msg":"trace[427370254] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"207.659603ms","start":"2026-05-20T17:30:31.015318Z","end":"2026-05-20T17:30:31.222978Z","steps":["trace[427370254] 'read index received' (duration: 207.436907ms)","trace[427370254] 'applied index is now lower than readState.Index' (duration: 221.035µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:30:31.223127Z","caller":"traceutil/trace.go:171","msg":"trace[898591958] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"271.067015ms","start":"2026-05-20T17:30:30.952017Z","end":"2026-05-20T17:30:31.223084Z","steps":["trace[898591958] 'process raft request' (duration: 270.719467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:31.223197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.346943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:30:31.223239Z","caller":"traceutil/trace.go:171","msg":"trace[430876292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"280.451656ms","start":"2026-05-20T17:30:30.942775Z","end":"2026-05-20T17:30:31.223227Z","steps":["trace[430876292] 'agreement among raft nodes before linearized reading' (duration: 280.338583ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:31.264065Z","caller":"traceutil/trace.go:171","msg":"trace[1035352635] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"243.88109ms","start":"2026-05-20T17:30:31.020161Z","end":"2026-05-20T17:30:31.264042Z","steps":["trace[1035352635] 'process raft request' (duration: 243.668745ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:31.264531Z","caller":"traceutil/trace.go:171","msg":"trace[1789299849] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"244.239258ms","start":"2026-05-20T17:30:31.020266Z","end":"2026-05-20T17:30:31.264506Z","steps":["trace[1789299849] 'process raft request' (duration: 243.720766ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:31.264944Z","caller":"traceutil/trace.go:171","msg":"trace[1259567274] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"244.625298ms","start":"2026-05-20T17:30:31.020301Z","end":"2026-05-20T17:30:31.264927Z","steps":["trace[1259567274] 'process raft request' (duration: 243.980992ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:31.265012Z","caller":"traceutil/trace.go:171","msg":"trace[833387440] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"243.562652ms","start":"2026-05-20T17:30:31.021419Z","end":"2026-05-20T17:30:31.264982Z","steps":["trace[833387440] 'process raft request' (duration: 243.448879ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:31.265155Z","caller":"traceutil/trace.go:171","msg":"trace[135951663] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"244.454495ms","start":"2026-05-20T17:30:31.020681Z","end":"2026-05-20T17:30:31.265136Z","steps":["trace[135951663] 'process raft request' (duration: 244.119316ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:31.265276Z","caller":"traceutil/trace.go:171","msg":"trace[825822333] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"243.096681ms","start":"2026-05-20T17:30:31.022168Z","end":"2026-05-20T17:30:31.265265Z","steps":["trace[825822333] 'process raft request' (duration: 242.737912ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:45.514115Z","caller":"traceutil/trace.go:171","msg":"trace[27095464] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"116.255236ms","start":"2026-05-20T17:30:45.397816Z","end":"2026-05-20T17:30:45.514071Z","steps":["trace[27095464] 'process raft request' (duration: 115.946508ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:45.514128Z","caller":"traceutil/trace.go:171","msg":"trace[44336889] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"115.64046ms","start":"2026-05-20T17:30:45.398453Z","end":"2026-05-20T17:30:45.514094Z","steps":["trace[44336889] 'process raft request' (duration: 115.514087ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:45.514447Z","caller":"traceutil/trace.go:171","msg":"trace[1728444194] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"114.092632ms","start":"2026-05-20T17:30:45.400302Z","end":"2026-05-20T17:30:45.514395Z","steps":["trace[1728444194] 'process raft request' (duration: 113.970609ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:45.514498Z","caller":"traceutil/trace.go:171","msg":"trace[1111576991] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"115.406185ms","start":"2026-05-20T17:30:45.399062Z","end":"2026-05-20T17:30:45.514469Z","steps":["trace[1111576991] 'process raft request' (duration: 114.975605ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:51.608085Z","caller":"traceutil/trace.go:171","msg":"trace[609543520] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"128.173187ms","start":"2026-05-20T17:30:51.479887Z","end":"2026-05-20T17:30:51.60806Z","steps":["trace[609543520] 'read index received' (duration: 19.748283ms)","trace[609543520] 'applied index is now lower than readState.Index' (duration: 108.424064ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:30:51.608197Z","caller":"traceutil/trace.go:171","msg":"trace[517620347] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"141.105344ms","start":"2026-05-20T17:30:51.46707Z","end":"2026-05-20T17:30:51.608175Z","steps":["trace[517620347] 'process raft request' (duration: 113.496248ms)","trace[517620347] 'compare' (duration: 27.38447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:30:51.608311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.420833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-20T17:30:51.608345Z","caller":"traceutil/trace.go:171","msg":"trace[377652097] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1421; }","duration":"128.482305ms","start":"2026-05-20T17:30:51.479851Z","end":"2026-05-20T17:30:51.608333Z","steps":["trace[377652097] 'agreement among raft nodes before linearized reading' (duration: 128.337352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:30:51.60864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.308161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:30:51.608721Z","caller":"traceutil/trace.go:171","msg":"trace[1912256690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1421; }","duration":"128.7026ms","start":"2026-05-20T17:30:51.479996Z","end":"2026-05-20T17:30:51.608698Z","steps":["trace[1912256690] 'agreement among raft nodes before linearized reading' (duration: 128.29873ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:51.738316Z","caller":"traceutil/trace.go:171","msg":"trace[507898091] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"124.634232ms","start":"2026-05-20T17:30:51.613654Z","end":"2026-05-20T17:30:51.738289Z","steps":["trace[507898091] 'process raft request' (duration: 102.459449ms)","trace[507898091] 'compare' (duration: 21.975568ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:30:51.738682Z","caller":"traceutil/trace.go:171","msg":"trace[793282861] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"124.956549ms","start":"2026-05-20T17:30:51.613715Z","end":"2026-05-20T17:30:51.738671Z","steps":["trace[793282861] 'process raft request' (duration: 124.527329ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:51.73872Z","caller":"traceutil/trace.go:171","msg":"trace[701466254] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"124.9754ms","start":"2026-05-20T17:30:51.613723Z","end":"2026-05-20T17:30:51.738698Z","steps":["trace[701466254] 'process raft request' (duration: 124.552109ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:30:51.738778Z","caller":"traceutil/trace.go:171","msg":"trace[1993024961] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"125.058131ms","start":"2026-05-20T17:30:51.613711Z","end":"2026-05-20T17:30:51.738769Z","steps":["trace[1993024961] 'process raft request' (duration: 124.487998ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:31:28.122315Z","caller":"traceutil/trace.go:171","msg":"trace[974194111] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"114.728218ms","start":"2026-05-20T17:31:28.007553Z","end":"2026-05-20T17:31:28.122282Z","steps":["trace[974194111] 'process raft request' (duration: 114.568744ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:31:29.84182Z","caller":"traceutil/trace.go:171","msg":"trace[27687974] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"153.771118ms","start":"2026-05-20T17:31:29.688028Z","end":"2026-05-20T17:31:29.841799Z","steps":["trace[27687974] 'process raft request' (duration: 153.613894ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:32:05.612016Z","caller":"traceutil/trace.go:171","msg":"trace[153812415] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"136.931442ms","start":"2026-05-20T17:32:05.475052Z","end":"2026-05-20T17:32:05.611984Z","steps":["trace[153812415] 'process raft request' (duration: 136.689556ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:32:10.50719Z","caller":"traceutil/trace.go:171","msg":"trace[1820969892] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"144.830437ms","start":"2026-05-20T17:32:10.362316Z","end":"2026-05-20T17:32:10.507147Z","steps":["trace[1820969892] 'process raft request' (duration: 144.54623ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:32:34.838225Z","caller":"traceutil/trace.go:171","msg":"trace[2041235933] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"124.556564ms","start":"2026-05-20T17:32:34.713649Z","end":"2026-05-20T17:32:34.838205Z","steps":["trace[2041235933] 'process raft request' (duration: 121.109681ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:33:08.657031Z","caller":"traceutil/trace.go:171","msg":"trace[895905192] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"125.372513ms","start":"2026-05-20T17:33:08.531628Z","end":"2026-05-20T17:33:08.657Z","steps":["trace[895905192] 'process raft request' (duration: 125.124607ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:34:27.540901Z","caller":"traceutil/trace.go:171","msg":"trace[1998977354] linearizableReadLoop","detail":"{readStateIndex:2538; appliedIndex:2537; }","duration":"106.775157ms","start":"2026-05-20T17:34:27.434098Z","end":"2026-05-20T17:34:27.540873Z","steps":["trace[1998977354] 'read index received' (duration: 106.660255ms)","trace[1998977354] 'applied index is now lower than readState.Index' (duration: 113.582µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:34:27.54127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.142317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-20T17:34:27.541349Z","caller":"traceutil/trace.go:171","msg":"trace[821259620] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2436; }","duration":"107.249319ms","start":"2026-05-20T17:34:27.434078Z","end":"2026-05-20T17:34:27.541327Z","steps":["trace[821259620] 'agreement among raft nodes before linearized reading' (duration: 106.90539ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:34:27.541513Z","caller":"traceutil/trace.go:171","msg":"trace[829242536] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"107.834783ms","start":"2026-05-20T17:34:27.433661Z","end":"2026-05-20T17:34:27.541495Z","steps":["trace[829242536] 'process raft request' (duration: 107.012822ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:34:27.615024Z","caller":"traceutil/trace.go:171","msg":"trace[2074029440] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"180.047113ms","start":"2026-05-20T17:34:27.4349Z","end":"2026-05-20T17:34:27.614947Z","steps":["trace[2074029440] 'process raft request' (duration: 179.915409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:27.615364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.570928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-20T17:34:27.615406Z","caller":"traceutil/trace.go:171","msg":"trace[1751959990] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2437; }","duration":"127.661379ms","start":"2026-05-20T17:34:27.487732Z","end":"2026-05-20T17:34:27.615394Z","steps":["trace[1751959990] 'agreement among raft nodes before linearized reading' (duration: 127.473135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:27.615415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.660077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:34:27.615548Z","caller":"traceutil/trace.go:171","msg":"trace[1044489745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2437; }","duration":"162.843241ms","start":"2026-05-20T17:34:27.452675Z","end":"2026-05-20T17:34:27.615518Z","steps":["trace[1044489745] 'agreement among raft nodes before linearized reading' (duration: 162.467092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:27.615456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.033199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:34:27.615684Z","caller":"traceutil/trace.go:171","msg":"trace[329268105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2437; }","duration":"137.356107ms","start":"2026-05-20T17:34:27.478313Z","end":"2026-05-20T17:34:27.615669Z","steps":["trace[329268105] 'agreement among raft nodes before linearized reading' (duration: 137.016469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:51.487733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872867711809757,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:34:51.988171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872867711809757,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:34:52.488772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872867711809757,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:34:52.710308Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.732271374s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T17:34:52.710835Z","caller":"traceutil/trace.go:171","msg":"trace[1031529956] transaction","detail":"{read_only:false; response_revision:2552; number_of_response:1; }","duration":"1.732908639s","start":"2026-05-20T17:34:50.977895Z","end":"2026-05-20T17:34:52.710803Z","steps":["trace[1031529956] 'process raft request' (duration: 1.732696474s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:52.711038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:50.977873Z","time spent":"1.733056713s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:34:52.71203Z","caller":"traceutil/trace.go:171","msg":"trace[248746414] linearizableReadLoop","detail":"{readStateIndex:2661; appliedIndex:2659; }","duration":"1.724690429s","start":"2026-05-20T17:34:50.987315Z","end":"2026-05-20T17:34:52.712005Z","steps":["trace[248746414] 'read index received' (duration: 1.723322075s)","trace[248746414] 'applied index is now lower than readState.Index' (duration: 1.367144ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:34:52.7122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706719211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-05-20T17:34:52.712236Z","caller":"traceutil/trace.go:171","msg":"trace[2061339316] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:2553; }","duration":"1.706784132s","start":"2026-05-20T17:34:51.005441Z","end":"2026-05-20T17:34:52.712225Z","steps":["trace[2061339316] 'agreement among raft nodes before linearized reading' (duration: 1.706670849s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:52.712269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:51.005431Z","time spent":"1.706830803s","remote":"127.0.0.1:44086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5187,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-20T17:34:52.712244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.724904525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"warn","ts":"2026-05-20T17:34:52.712299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.562932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-20T17:34:52.712358Z","caller":"traceutil/trace.go:171","msg":"trace[1745406036] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2553; }","duration":"990.646495ms","start":"2026-05-20T17:34:51.721695Z","end":"2026-05-20T17:34:52.712341Z","steps":["trace[1745406036] 'agreement among raft nodes before linearized reading' (duration: 990.46627ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:34:52.71239Z","caller":"traceutil/trace.go:171","msg":"trace[310486584] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"518.11138ms","start":"2026-05-20T17:34:52.19426Z","end":"2026-05-20T17:34:52.712371Z","steps":["trace[310486584] 'process raft request' (duration: 517.600507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:52.712408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:51.721682Z","time spent":"990.713046ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-20T17:34:52.712519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:52.194237Z","time spent":"518.201011ms","remote":"127.0.0.1:44176","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-20T17:34:52.712554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484129382s","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-20T17:34:52.712589Z","caller":"traceutil/trace.go:171","msg":"trace[571527121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2553; }","duration":"1.484162033s","start":"2026-05-20T17:34:51.228415Z","end":"2026-05-20T17:34:52.712577Z","steps":["trace[571527121] 'agreement among raft nodes before linearized reading' (duration: 1.48405308s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:52.712617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:51.228374Z","time spent":"1.484237644s","remote":"127.0.0.1:44176","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-20T17:34:52.712724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.116807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-20T17:34:52.712791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494561927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T17:34:52.71282Z","caller":"traceutil/trace.go:171","msg":"trace[1655486187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2553; }","duration":"946.24907ms","start":"2026-05-20T17:34:51.766553Z","end":"2026-05-20T17:34:52.712802Z","steps":["trace[1655486187] 'agreement among raft nodes before linearized reading' (duration: 946.097966ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:34:52.712864Z","caller":"traceutil/trace.go:171","msg":"trace[116610525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2553; }","duration":"1.49469459s","start":"2026-05-20T17:34:51.218152Z","end":"2026-05-20T17:34:52.712847Z","steps":["trace[116610525] 'agreement among raft nodes before linearized reading' (duration: 1.494519976s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:52.712667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391270313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-20T17:34:52.712907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:51.218125Z","time spent":"1.494773401s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-20T17:34:52.712917Z","caller":"traceutil/trace.go:171","msg":"trace[1916039471] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2553; }","duration":"1.39157668s","start":"2026-05-20T17:34:51.321327Z","end":"2026-05-20T17:34:52.712904Z","steps":["trace[1916039471] 'agreement among raft nodes before linearized reading' (duration: 1.391167801s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:52.712985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:51.321309Z","time spent":"1.391661902s","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-20T17:34:52.712869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:51.766532Z","time spent":"946.323741ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T17:34:52.71299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028699514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-20T17:34:52.713082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.232340909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:34:52.713094Z","caller":"traceutil/trace.go:171","msg":"trace[1314311832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2553; }","duration":"1.028860548s","start":"2026-05-20T17:34:51.684203Z","end":"2026-05-20T17:34:52.713063Z","steps":["trace[1314311832] 'agreement among raft nodes before linearized reading' (duration: 1.028612842s)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:34:52.713128Z","caller":"traceutil/trace.go:171","msg":"trace[628813793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2553; }","duration":"1.232429481s","start":"2026-05-20T17:34:51.480686Z","end":"2026-05-20T17:34:52.713115Z","steps":["trace[628813793] 'agreement among raft nodes before linearized reading' (duration: 1.23235959s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:52.713148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:51.684193Z","time spent":"1.02894593s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T17:34:52.713166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:51.480665Z","time spent":"1.232490903s","remote":"127.0.0.1:43882","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-20T17:34:52.712324Z","caller":"traceutil/trace.go:171","msg":"trace[1814306295] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2553; }","duration":"1.725049338s","start":"2026-05-20T17:34:50.987256Z","end":"2026-05-20T17:34:52.712305Z","steps":["trace[1814306295] 'agreement among raft nodes before linearized reading' (duration: 1.724853853s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:34:52.713445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:34:50.987232Z","time spent":"1.726197645s","remote":"127.0.0.1:44086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7134,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "}