{"level":"warn","ts":"2026-03-03T13:56:19.702185Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T13:56:19.702283Z","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-03-03T13:56:19.702375Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T13:56:19.702394Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-03-03T13:56:19.702419Z","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-03-03T13:56:19.703303Z","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-03-03T13:56:19.703395Z","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-03-03T13:56:19.707565Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.928119ms"} {"level":"info","ts":"2026-03-03T13:56:19.713491Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-03-03T13:56:19.713707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T13:56:19.713736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-03-03T13:56:19.71376Z","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-03-03T13:56:19.713772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-03-03T13:56:19.713885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-03-03T13:56:19.725289Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T13:56:19.726563Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T13:56:19.727535Z","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-03-03T13:56:19.729467Z","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-03-03T13:56:19.7297Z","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-03-03T13:56:19.729845Z","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-03-03T13:56:19.729922Z","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-03-03T13:56:19.729942Z","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-03-03T13:56:19.73349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-03-03T13:56:19.733821Z","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-03-03T13:56:19.736296Z","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-03-03T13:56:19.736478Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-03-03T13:56:19.736517Z","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-03-03T13:56:19.736535Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-03-03T13:56:19.736567Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T13:56:20.014934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T13:56:20.014992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T13:56:20.01501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-03-03T13:56:20.015023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-03-03T13:56:20.015029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-03-03T13:56:20.01504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-03-03T13:56:20.015062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-03-03T13:56:20.354849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T13:56:20.354851Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T13:56:20.354854Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T13:56:20.355337Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T13:56:20.355384Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T13:56:20.355384Z","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-03-03T13:56:20.357223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-03-03T13:56:20.357644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-03T13:56:20.630432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.110151ms","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-03-03T13:56:20.630507Z","caller":"traceutil/trace.go:171","msg":"trace[143700498] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"126.257875ms","start":"2026-03-03T13:56:20.504239Z","end":"2026-03-03T13:56:20.630496Z","steps":["trace[143700498] 'agreement among raft nodes before linearized reading' (duration: 126.166393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.630489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.248434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"","error":"context canceled"} 2026/03/03 13:56:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-03-03T13:56:20.63056Z","caller":"traceutil/trace.go:171","msg":"trace[1061883407] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"126.357837ms","start":"2026-03-03T13:56:20.504191Z","end":"2026-03-03T13:56:20.630549Z","steps":["trace[1061883407] 'agreement among raft nodes before linearized reading' (duration: 126.266335ms)"],"step_count":1} 2026/03/03 13:56:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-03-03T13:56:20.718931Z","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-03-03T13:56:20.719106Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T13:56:20.719184Z","caller":"traceutil/trace.go:171","msg":"trace[1883677329] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"316.667002ms","start":"2026-03-03T13:56:20.402489Z","end":"2026-03-03T13:56:20.719156Z","steps":["trace[1883677329] 'read index received' (duration: 316.359316ms)","trace[1883677329] 'applied index is now lower than readState.Index' (duration: 306.346µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:56:20.719187Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-03T13:56:20.71937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.761624ms","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":"info","ts":"2026-03-03T13:56:20.719408Z","caller":"traceutil/trace.go:171","msg":"trace[1753096194] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"316.836977ms","start":"2026-03-03T13:56:20.402558Z","end":"2026-03-03T13:56:20.719395Z","steps":["trace[1753096194] 'agreement among raft nodes before linearized reading' (duration: 316.751945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.719424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.766905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:20.71947Z","caller":"traceutil/trace.go:171","msg":"trace[834630281] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"140.803426ms","start":"2026-03-03T13:56:20.578649Z","end":"2026-03-03T13:56:20.719452Z","steps":["trace[834630281] 'agreement among raft nodes before linearized reading' (duration: 140.751955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.719521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.813574ms","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":"info","ts":"2026-03-03T13:56:20.719544Z","caller":"traceutil/trace.go:171","msg":"trace[917247505] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"153.853964ms","start":"2026-03-03T13:56:20.565684Z","end":"2026-03-03T13:56:20.719538Z","steps":["trace[917247505] 'agreement among raft nodes before linearized reading' (duration: 153.805173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.719584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.926246ms","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":"info","ts":"2026-03-03T13:56:20.71961Z","caller":"traceutil/trace.go:171","msg":"trace[741871278] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"153.958466ms","start":"2026-03-03T13:56:20.56564Z","end":"2026-03-03T13:56:20.719598Z","steps":["trace[741871278] 'agreement among raft nodes before linearized reading' (duration: 153.927016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.719642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.137494ms","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":"info","ts":"2026-03-03T13:56:20.71966Z","caller":"traceutil/trace.go:171","msg":"trace[938677649] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"166.176945ms","start":"2026-03-03T13:56:20.553478Z","end":"2026-03-03T13:56:20.719655Z","steps":["trace[938677649] 'agreement among raft nodes before linearized reading' (duration: 166.151404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.719908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.40636ms","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-03-03T13:56:20.719935Z","caller":"traceutil/trace.go:171","msg":"trace[1345606971] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"166.453592ms","start":"2026-03-03T13:56:20.553474Z","end":"2026-03-03T13:56:20.719928Z","steps":["trace[1345606971] 'agreement among raft nodes before linearized reading' (duration: 166.412221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.720046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.751887ms","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-03-03T13:56:20.720077Z","caller":"traceutil/trace.go:171","msg":"trace[708299187] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"176.784588ms","start":"2026-03-03T13:56:20.543284Z","end":"2026-03-03T13:56:20.720068Z","steps":["trace[708299187] 'agreement among raft nodes before linearized reading' (duration: 176.738417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.720232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.123445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:20.720282Z","caller":"traceutil/trace.go:171","msg":"trace[1055759909] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"103.212307ms","start":"2026-03-03T13:56:20.617058Z","end":"2026-03-03T13:56:20.720271Z","steps":["trace[1055759909] 'agreement among raft nodes before linearized reading' (duration: 103.137276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.720277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.794107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:20.720346Z","caller":"traceutil/trace.go:171","msg":"trace[513810739] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"126.890739ms","start":"2026-03-03T13:56:20.593437Z","end":"2026-03-03T13:56:20.720328Z","steps":["trace[513810739] 'agreement among raft nodes before linearized reading' (duration: 126.769996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.72038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.247291ms","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":"info","ts":"2026-03-03T13:56:20.720405Z","caller":"traceutil/trace.go:171","msg":"trace[1471436970] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"252.296092ms","start":"2026-03-03T13:56:20.468102Z","end":"2026-03-03T13:56:20.720398Z","steps":["trace[1471436970] 'agreement among raft nodes before linearized reading' (duration: 252.264392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.720515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.409335ms","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":"warn","ts":"2026-03-03T13:56:20.720528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.518804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:20.720537Z","caller":"traceutil/trace.go:171","msg":"trace[351286752] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"252.455666ms","start":"2026-03-03T13:56:20.468076Z","end":"2026-03-03T13:56:20.720532Z","steps":["trace[351286752] 'agreement among raft nodes before linearized reading' (duration: 252.422966ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:20.72055Z","caller":"traceutil/trace.go:171","msg":"trace[21821236] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"103.554546ms","start":"2026-03-03T13:56:20.616989Z","end":"2026-03-03T13:56:20.720543Z","steps":["trace[21821236] 'agreement among raft nodes before linearized reading' (duration: 103.520205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.720659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.086103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:20.720714Z","caller":"traceutil/trace.go:171","msg":"trace[378938533] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"127.156665ms","start":"2026-03-03T13:56:20.593539Z","end":"2026-03-03T13:56:20.720695Z","steps":["trace[378938533] 'agreement among raft nodes before linearized reading' (duration: 127.048223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.720926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.035122ms","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-03-03T13:56:20.72035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.912968ms","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-03-03T13:56:20.721034Z","caller":"traceutil/trace.go:171","msg":"trace[499760828] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"243.625174ms","start":"2026-03-03T13:56:20.477401Z","end":"2026-03-03T13:56:20.721026Z","steps":["trace[499760828] 'agreement among raft nodes before linearized reading' (duration: 242.869517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.721079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.646302ms","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":"info","ts":"2026-03-03T13:56:20.721105Z","caller":"traceutil/trace.go:171","msg":"trace[183795115] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"263.681172ms","start":"2026-03-03T13:56:20.457417Z","end":"2026-03-03T13:56:20.721098Z","steps":["trace[183795115] 'agreement among raft nodes before linearized reading' (duration: 263.60881ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:20.721048Z","caller":"traceutil/trace.go:171","msg":"trace[1984768984] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"243.564303ms","start":"2026-03-03T13:56:20.477451Z","end":"2026-03-03T13:56:20.721015Z","steps":["trace[1984768984] 'agreement among raft nodes before linearized reading' (duration: 242.782246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.720976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.216078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:20.721277Z","caller":"traceutil/trace.go:171","msg":"trace[2009852202] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"142.518845ms","start":"2026-03-03T13:56:20.57875Z","end":"2026-03-03T13:56:20.721269Z","steps":["trace[2009852202] 'agreement among raft nodes before linearized reading' (duration: 142.203578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.721337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.928267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:20.71937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.846076ms","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-03-03T13:56:20.721366Z","caller":"traceutil/trace.go:171","msg":"trace[1218275051] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"115.967858ms","start":"2026-03-03T13:56:20.605391Z","end":"2026-03-03T13:56:20.721359Z","steps":["trace[1218275051] 'agreement among raft nodes before linearized reading' (duration: 115.924637ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:20.721412Z","caller":"traceutil/trace.go:171","msg":"trace[310333235] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"318.892833ms","start":"2026-03-03T13:56:20.402484Z","end":"2026-03-03T13:56:20.721377Z","steps":["trace[310333235] 'agreement among raft nodes before linearized reading' (duration: 316.770555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.721449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:20.40247Z","time spent":"318.964236ms","remote":"127.0.0.1:59026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-03-03T13:56:20.721493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.118492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:20.721512Z","caller":"traceutil/trace.go:171","msg":"trace[1585424523] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"116.149182ms","start":"2026-03-03T13:56:20.605357Z","end":"2026-03-03T13:56:20.721507Z","steps":["trace[1585424523] 'agreement among raft nodes before linearized reading' (duration: 116.121082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.721664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.266688ms","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-03-03T13:56:20.721698Z","caller":"traceutil/trace.go:171","msg":"trace[448819298] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"205.302528ms","start":"2026-03-03T13:56:20.516388Z","end":"2026-03-03T13:56:20.72169Z","steps":["trace[448819298] 'agreement among raft nodes before linearized reading' (duration: 205.255327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.721813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.658251ms","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":"info","ts":"2026-03-03T13:56:20.721839Z","caller":"traceutil/trace.go:171","msg":"trace[2046084542] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"178.699822ms","start":"2026-03-03T13:56:20.543132Z","end":"2026-03-03T13:56:20.721832Z","steps":["trace[2046084542] 'agreement among raft nodes before linearized reading' (duration: 178.654891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.721867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.46531ms","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-03-03T13:56:20.721891Z","caller":"traceutil/trace.go:171","msg":"trace[1715345992] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"264.514031ms","start":"2026-03-03T13:56:20.45737Z","end":"2026-03-03T13:56:20.721884Z","steps":["trace[1715345992] 'agreement among raft nodes before linearized reading' (duration: 264.4676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.721927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.675455ms","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":"info","ts":"2026-03-03T13:56:20.72195Z","caller":"traceutil/trace.go:171","msg":"trace[419803500] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"190.698575ms","start":"2026-03-03T13:56:20.531245Z","end":"2026-03-03T13:56:20.721943Z","steps":["trace[419803500] 'agreement among raft nodes before linearized reading' (duration: 190.666514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.722019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.684817ms","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-03-03T13:56:20.722041Z","caller":"traceutil/trace.go:171","msg":"trace[1965819336] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"275.756018ms","start":"2026-03-03T13:56:20.446279Z","end":"2026-03-03T13:56:20.722035Z","steps":["trace[1965819336] 'agreement among raft nodes before linearized reading' (duration: 275.718627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.722043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.90666ms","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-03-03T13:56:20.722066Z","caller":"traceutil/trace.go:171","msg":"trace[1452241672] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"190.938381ms","start":"2026-03-03T13:56:20.531117Z","end":"2026-03-03T13:56:20.722055Z","steps":["trace[1452241672] 'agreement among raft nodes before linearized reading' (duration: 190.91119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.722977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.654908ms","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-03-03T13:56:20.72301Z","caller":"traceutil/trace.go:171","msg":"trace[1632923587] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"230.7101ms","start":"2026-03-03T13:56:20.492293Z","end":"2026-03-03T13:56:20.723003Z","steps":["trace[1632923587] 'agreement among raft nodes before linearized reading' (duration: 230.656979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.723123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.611658ms","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":"info","ts":"2026-03-03T13:56:20.723153Z","caller":"traceutil/trace.go:171","msg":"trace[1740311902] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"230.692019ms","start":"2026-03-03T13:56:20.492454Z","end":"2026-03-03T13:56:20.723146Z","steps":["trace[1740311902] 'agreement among raft nodes before linearized reading' (duration: 230.403043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:20.719447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:20.402554Z","time spent":"316.881988ms","remote":"127.0.0.1:59026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":121,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 "} {"level":"warn","ts":"2026-03-03T13:56:20.723239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.945197ms","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-03-03T13:56:20.72332Z","caller":"traceutil/trace.go:171","msg":"trace[745838369] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"207.037489ms","start":"2026-03-03T13:56:20.516275Z","end":"2026-03-03T13:56:20.723313Z","steps":["trace[745838369] 'agreement among raft nodes before linearized reading' (duration: 206.950557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.130289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.684721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.130374Z","caller":"traceutil/trace.go:171","msg":"trace[1531966648] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1; }","duration":"333.770784ms","start":"2026-03-03T13:56:20.796584Z","end":"2026-03-03T13:56:21.130355Z","steps":["trace[1531966648] 'count revisions from in-memory index tree' (duration: 333.60534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.13036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.848752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.130422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:20.796576Z","time spent":"333.835605ms","remote":"127.0.0.1:59332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-03-03T13:56:21.13043Z","caller":"traceutil/trace.go:171","msg":"trace[798461264] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1; }","duration":"219.968474ms","start":"2026-03-03T13:56:20.910445Z","end":"2026-03-03T13:56:21.130414Z","steps":["trace[798461264] 'count revisions from in-memory index tree' (duration: 219.768019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.130434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.039584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.130489Z","caller":"traceutil/trace.go:171","msg":"trace[943370353] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1; }","duration":"135.116056ms","start":"2026-03-03T13:56:20.995359Z","end":"2026-03-03T13:56:21.130475Z","steps":["trace[943370353] 'count revisions from in-memory index tree' (duration: 134.992623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.130579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.974545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.130621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.926092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.130656Z","caller":"traceutil/trace.go:171","msg":"trace[156990515] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1; }","duration":"276.005643ms","start":"2026-03-03T13:56:20.854637Z","end":"2026-03-03T13:56:21.130643Z","steps":["trace[156990515] 'count revisions from in-memory index tree' (duration: 275.85559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.130706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.242699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.130642Z","caller":"traceutil/trace.go:171","msg":"trace[11799647] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1; }","duration":"185.061887ms","start":"2026-03-03T13:56:20.945565Z","end":"2026-03-03T13:56:21.130627Z","steps":["trace[11799647] 'count revisions from in-memory index tree' (duration: 184.76184ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.130788Z","caller":"traceutil/trace.go:171","msg":"trace[1132092629] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1; }","duration":"135.345701ms","start":"2026-03-03T13:56:20.995426Z","end":"2026-03-03T13:56:21.130772Z","steps":["trace[1132092629] 'range keys from in-memory index tree' (duration: 135.182827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.130843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.304855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.130848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.153238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.130878Z","caller":"traceutil/trace.go:171","msg":"trace[1990844456] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1; }","duration":"239.343066ms","start":"2026-03-03T13:56:20.891527Z","end":"2026-03-03T13:56:21.13087Z","steps":["trace[1990844456] 'range keys from in-memory index tree' (duration: 239.249714ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.130886Z","caller":"traceutil/trace.go:171","msg":"trace[537206605] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1; }","duration":"185.19401ms","start":"2026-03-03T13:56:20.945681Z","end":"2026-03-03T13:56:21.130875Z","steps":["trace[537206605] 'range keys from in-memory index tree' (duration: 185.105388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.130892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.514381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.130974Z","caller":"traceutil/trace.go:171","msg":"trace[780430042] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1; }","duration":"260.640243ms","start":"2026-03-03T13:56:20.870316Z","end":"2026-03-03T13:56:21.130956Z","steps":["trace[780430042] 'count revisions from in-memory index tree' (duration: 260.441279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.131016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.264944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.131052Z","caller":"traceutil/trace.go:171","msg":"trace[1227522880] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1; }","duration":"239.316596ms","start":"2026-03-03T13:56:20.891727Z","end":"2026-03-03T13:56:21.131044Z","steps":["trace[1227522880] 'count revisions from in-memory index tree' (duration: 239.224363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.131059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.981461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.131051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.871517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.131042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.279347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.131124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.95748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.131121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.965119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.131152Z","caller":"traceutil/trace.go:171","msg":"trace[829387727] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1; }","duration":"208.409009ms","start":"2026-03-03T13:56:20.92273Z","end":"2026-03-03T13:56:21.131139Z","steps":["trace[829387727] 'range keys from in-memory index tree' (duration: 208.187005ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.131159Z","caller":"traceutil/trace.go:171","msg":"trace[2065781440] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1; }","duration":"173.027522ms","start":"2026-03-03T13:56:20.958124Z","end":"2026-03-03T13:56:21.131151Z","steps":["trace[2065781440] 'count revisions from in-memory index tree' (duration: 172.845547ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.131093Z","caller":"traceutil/trace.go:171","msg":"trace[105186301] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1; }","duration":"173.029852ms","start":"2026-03-03T13:56:20.958055Z","end":"2026-03-03T13:56:21.131084Z","steps":["trace[105186301] 'range keys from in-memory index tree' (duration: 172.9307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.131172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.718261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.133702Z","caller":"traceutil/trace.go:171","msg":"trace[2130341977] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1; }","duration":"223.25669ms","start":"2026-03-03T13:56:20.910432Z","end":"2026-03-03T13:56:21.133689Z","steps":["trace[2130341977] 'range keys from in-memory index tree' (duration: 220.67097ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.131179Z","caller":"traceutil/trace.go:171","msg":"trace[1872746550] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1; }","duration":"197.02104ms","start":"2026-03-03T13:56:20.934136Z","end":"2026-03-03T13:56:21.131157Z","steps":["trace[1872746550] 'count revisions from in-memory index tree' (duration: 196.897867ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.131096Z","caller":"traceutil/trace.go:171","msg":"trace[766144581] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1; }","duration":"196.953468ms","start":"2026-03-03T13:56:20.934135Z","end":"2026-03-03T13:56:21.131088Z","steps":["trace[766144581] 'range keys from in-memory index tree' (duration: 196.804344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.131187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.122629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.134392Z","caller":"traceutil/trace.go:171","msg":"trace[883131172] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1; }","duration":"164.330903ms","start":"2026-03-03T13:56:20.970045Z","end":"2026-03-03T13:56:21.134376Z","steps":["trace[883131172] 'count revisions from in-memory index tree' (duration: 161.085698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.131239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.564586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.134536Z","caller":"traceutil/trace.go:171","msg":"trace[425365010] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1; }","duration":"279.885592ms","start":"2026-03-03T13:56:20.854637Z","end":"2026-03-03T13:56:21.134522Z","steps":["trace[425365010] 'range keys from in-memory index tree' (duration: 276.468204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.13123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.508392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.13461Z","caller":"traceutil/trace.go:171","msg":"trace[1503758748] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1; }","duration":"211.908609ms","start":"2026-03-03T13:56:20.922691Z","end":"2026-03-03T13:56:21.134599Z","steps":["trace[1503758748] 'count revisions from in-memory index tree' (duration: 208.4218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.131279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.763986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.131279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.841296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.131357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.421999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.13025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.114307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.130273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.694402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.131692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.879612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.13173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.21542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T13:56:21.131766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.281482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.134732Z","caller":"traceutil/trace.go:171","msg":"trace[773427289] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1; }","duration":"111.266422ms","start":"2026-03-03T13:56:21.023454Z","end":"2026-03-03T13:56:21.13472Z","steps":["trace[773427289] 'range keys from in-memory index tree' (duration: 108.16295ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.134797Z","caller":"traceutil/trace.go:171","msg":"trace[1049580446] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1; }","duration":"128.033564ms","start":"2026-03-03T13:56:21.006753Z","end":"2026-03-03T13:56:21.134787Z","steps":["trace[1049580446] 'count revisions from in-memory index tree' (duration: 124.792709ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.134916Z","caller":"traceutil/trace.go:171","msg":"trace[812148234] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1; }","duration":"111.399004ms","start":"2026-03-03T13:56:21.023508Z","end":"2026-03-03T13:56:21.134907Z","steps":["trace[812148234] 'count revisions from in-memory index tree' (duration: 108.161719ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.134916Z","caller":"traceutil/trace.go:171","msg":"trace[1029430840] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1; }","duration":"164.665702ms","start":"2026-03-03T13:56:20.970091Z","end":"2026-03-03T13:56:21.134756Z","steps":["trace[1029430840] 'range keys from in-memory index tree' (duration: 160.037675ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.134938Z","caller":"traceutil/trace.go:171","msg":"trace[36306455] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1; }","duration":"338.375189ms","start":"2026-03-03T13:56:20.796554Z","end":"2026-03-03T13:56:21.134929Z","steps":["trace[36306455] 'range keys from in-memory index tree' (duration: 333.606459ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.134944Z","caller":"traceutil/trace.go:171","msg":"trace[1514581968] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1; }","duration":"264.437121ms","start":"2026-03-03T13:56:20.870498Z","end":"2026-03-03T13:56:21.134935Z","steps":["trace[1514581968] 'range keys from in-memory index tree' (duration: 260.734705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.134978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:20.796546Z","time spent":"338.416149ms","remote":"127.0.0.1:59332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" limit:10000 "} {"level":"info","ts":"2026-03-03T13:56:21.135084Z","caller":"traceutil/trace.go:171","msg":"trace[1739751555] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1; }","duration":"254.212117ms","start":"2026-03-03T13:56:20.88085Z","end":"2026-03-03T13:56:21.135062Z","steps":["trace[1739751555] 'range keys from in-memory index tree' (duration: 250.326637ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.134838Z","caller":"traceutil/trace.go:171","msg":"trace[1870222915] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1; }","duration":"253.797867ms","start":"2026-03-03T13:56:20.881027Z","end":"2026-03-03T13:56:21.134825Z","steps":["trace[1870222915] 'count revisions from in-memory index tree' (duration: 249.673992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.135303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.34106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.135376Z","caller":"traceutil/trace.go:171","msg":"trace[305034547] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1; }","duration":"128.779081ms","start":"2026-03-03T13:56:21.006574Z","end":"2026-03-03T13:56:21.135353Z","steps":["trace[305034547] 'range keys from in-memory index tree' (duration: 124.311428ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.771196Z","caller":"traceutil/trace.go:171","msg":"trace[198371884] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"126.491969ms","start":"2026-03-03T13:56:21.644661Z","end":"2026-03-03T13:56:21.771153Z","steps":["trace[198371884] 'process raft request' (duration: 126.150441ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:21.778347Z","caller":"traceutil/trace.go:171","msg":"trace[2141384499] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"123.435489ms","start":"2026-03-03T13:56:21.654892Z","end":"2026-03-03T13:56:21.778328Z","steps":["trace[2141384499] 'read index received' (duration: 115.912618ms)","trace[2141384499] 'applied index is now lower than readState.Index' (duration: 7.521961ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:56:21.778453Z","caller":"traceutil/trace.go:171","msg":"trace[172599709] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"133.678892ms","start":"2026-03-03T13:56:21.644765Z","end":"2026-03-03T13:56:21.778443Z","steps":["trace[172599709] 'process raft request' (duration: 133.264733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:21.780246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.339423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T13:56:21.780312Z","caller":"traceutil/trace.go:171","msg":"trace[541325585] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"125.426255ms","start":"2026-03-03T13:56:21.654874Z","end":"2026-03-03T13:56:21.780301Z","steps":["trace[541325585] 'agreement among raft nodes before linearized reading' (duration: 124.433593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:49.157461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.741486ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T13:56:49.157583Z","caller":"traceutil/trace.go:171","msg":"trace[1596095612] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"383.610402ms","start":"2026-03-03T13:56:48.773956Z","end":"2026-03-03T13:56:49.157566Z","steps":["trace[1596095612] 'read index received' (duration: 48.60094ms)","trace[1596095612] 'applied index is now lower than readState.Index' (duration: 335.008142ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:56:49.157743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.963717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-03T13:56:49.157718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.738965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T13:56:49.157769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.754068ms","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-03-03T13:56:49.157789Z","caller":"traceutil/trace.go:171","msg":"trace[1242554272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"203.051779ms","start":"2026-03-03T13:56:48.954727Z","end":"2026-03-03T13:56:49.157779Z","steps":["trace[1242554272] 'agreement among raft nodes before linearized reading' (duration: 202.949507ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:49.157818Z","caller":"traceutil/trace.go:171","msg":"trace[323213117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"383.872288ms","start":"2026-03-03T13:56:48.773921Z","end":"2026-03-03T13:56:49.157793Z","steps":["trace[323213117] 'agreement among raft nodes before linearized reading' (duration: 383.688144ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:49.157826Z","caller":"traceutil/trace.go:171","msg":"trace[1963928016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"377.8288ms","start":"2026-03-03T13:56:48.779982Z","end":"2026-03-03T13:56:49.157811Z","steps":["trace[1963928016] 'agreement among raft nodes before linearized reading' (duration: 377.695947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:49.157836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.582744ms","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-03-03T13:56:49.157864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:48.773905Z","time spent":"383.95076ms","remote":"127.0.0.1:58954","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-03-03T13:56:49.157916Z","caller":"traceutil/trace.go:171","msg":"trace[133091006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"377.720417ms","start":"2026-03-03T13:56:48.780176Z","end":"2026-03-03T13:56:49.157896Z","steps":["trace[133091006] 'agreement among raft nodes before linearized reading' (duration: 377.526173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:49.157962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:48.780137Z","time spent":"377.81672ms","remote":"127.0.0.1:59262","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-03-03T13:56:49.157864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:48.779976Z","time spent":"377.879342ms","remote":"127.0.0.1:59262","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-03-03T13:56:49.455848Z","caller":"traceutil/trace.go:171","msg":"trace[359282385] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"293.208376ms","start":"2026-03-03T13:56:49.162617Z","end":"2026-03-03T13:56:49.455825Z","steps":["trace[359282385] 'process raft request' (duration: 293.041342ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:49.457257Z","caller":"traceutil/trace.go:171","msg":"trace[1815941419] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"294.247051ms","start":"2026-03-03T13:56:49.16299Z","end":"2026-03-03T13:56:49.457237Z","steps":["trace[1815941419] 'process raft request' (duration: 294.20533ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:49.45732Z","caller":"traceutil/trace.go:171","msg":"trace[755173353] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"294.589188ms","start":"2026-03-03T13:56:49.162714Z","end":"2026-03-03T13:56:49.457303Z","steps":["trace[755173353] 'process raft request' (duration: 294.377224ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:50.531635Z","caller":"traceutil/trace.go:171","msg":"trace[1837992954] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"209.793253ms","start":"2026-03-03T13:56:50.32182Z","end":"2026-03-03T13:56:50.531613Z","steps":["trace[1837992954] 'process raft request' (duration: 209.65979ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:56.211368Z","caller":"traceutil/trace.go:171","msg":"trace[1482597463] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"263.034338ms","start":"2026-03-03T13:56:55.948307Z","end":"2026-03-03T13:56:56.211341Z","steps":["trace[1482597463] 'process raft request' (duration: 262.775892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:56.590151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.650709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gt7n8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-03T13:56:56.590235Z","caller":"traceutil/trace.go:171","msg":"trace[195915010] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gt7n8; range_end:; response_count:1; response_revision:491; }","duration":"262.746201ms","start":"2026-03-03T13:56:56.32747Z","end":"2026-03-03T13:56:56.590216Z","steps":["trace[195915010] 'range keys from in-memory index tree' (duration: 262.487656ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:56.731158Z","caller":"traceutil/trace.go:171","msg":"trace[1631147573] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"137.813407ms","start":"2026-03-03T13:56:56.59332Z","end":"2026-03-03T13:56:56.731134Z","steps":["trace[1631147573] 'process raft request' (duration: 137.639454ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:56.867137Z","caller":"traceutil/trace.go:171","msg":"trace[1382535198] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"263.589121ms","start":"2026-03-03T13:56:56.603521Z","end":"2026-03-03T13:56:56.86711Z","steps":["trace[1382535198] 'process raft request' (duration: 227.725351ms)","trace[1382535198] 'compare' (duration: 35.699106ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:56:58.138833Z","caller":"traceutil/trace.go:171","msg":"trace[1714352094] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"649.889334ms","start":"2026-03-03T13:56:57.488919Z","end":"2026-03-03T13:56:58.138808Z","steps":["trace[1714352094] 'process raft request' (duration: 649.736981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:58.139417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:57.488898Z","time spent":"650.011656ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T13:56:58.233677Z","caller":"traceutil/trace.go:171","msg":"trace[525927922] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"744.466484ms","start":"2026-03-03T13:56:57.489187Z","end":"2026-03-03T13:56:58.233653Z","steps":["trace[525927922] 'process raft request' (duration: 744.28414ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:58.233848Z","caller":"traceutil/trace.go:171","msg":"trace[874730852] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:512; }","duration":"460.352205ms","start":"2026-03-03T13:56:57.773472Z","end":"2026-03-03T13:56:58.233824Z","steps":["trace[874730852] 'read index received' (duration: 365.177301ms)","trace[874730852] 'applied index is now lower than readState.Index' (duration: 95.173574ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:56:58.233949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.475609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:56:58.233987Z","caller":"traceutil/trace.go:171","msg":"trace[754526262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"460.555261ms","start":"2026-03-03T13:56:57.773421Z","end":"2026-03-03T13:56:58.233976Z","steps":["trace[754526262] 'agreement among raft nodes before linearized reading' (duration: 460.485099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:58.233949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:57.489173Z","time spent":"744.653598ms","remote":"127.0.0.1:59262","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-03-03T13:56:58.234017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:57.773402Z","time spent":"460.608981ms","remote":"127.0.0.1:58962","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-03-03T13:56:58.233922Z","caller":"traceutil/trace.go:171","msg":"trace[643158967] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"744.602847ms","start":"2026-03-03T13:56:57.489252Z","end":"2026-03-03T13:56:58.233855Z","steps":["trace[643158967] 'process raft request' (duration: 744.364041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:58.234786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:57.489217Z","time spent":"745.455276ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T13:56:59.634062Z","caller":"traceutil/trace.go:171","msg":"trace[539497359] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"188.332513ms","start":"2026-03-03T13:56:59.445705Z","end":"2026-03-03T13:56:59.634038Z","steps":["trace[539497359] 'process raft request' (duration: 188.088446ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:59.789906Z","caller":"traceutil/trace.go:171","msg":"trace[1611583771] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:536; }","duration":"162.954032ms","start":"2026-03-03T13:56:59.62693Z","end":"2026-03-03T13:56:59.789884Z","steps":["trace[1611583771] 'read index received' (duration: 6.800545ms)","trace[1611583771] 'applied index is now lower than readState.Index' (duration: 156.152407ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:56:59.790019Z","caller":"traceutil/trace.go:171","msg":"trace[1094478628] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"343.68429ms","start":"2026-03-03T13:56:59.446323Z","end":"2026-03-03T13:56:59.790007Z","steps":["trace[1094478628] 'process raft request' (duration: 343.229109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:56:59.790113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:59.446317Z","time spent":"343.734681ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T13:56:59.790202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.271839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:56:59.790238Z","caller":"traceutil/trace.go:171","msg":"trace[24874992] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:519; }","duration":"163.358861ms","start":"2026-03-03T13:56:59.626868Z","end":"2026-03-03T13:56:59.790227Z","steps":["trace[24874992] 'agreement among raft nodes before linearized reading' (duration: 163.272389ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:59.790097Z","caller":"traceutil/trace.go:171","msg":"trace[659983571] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"344.075849ms","start":"2026-03-03T13:56:59.446002Z","end":"2026-03-03T13:56:59.790077Z","steps":["trace[659983571] 'process raft request' (duration: 307.80003ms)","trace[659983571] 'compare' (duration: 35.588694ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:56:59.790317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:56:59.445984Z","time spent":"344.294374ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T13:56:59.790487Z","caller":"traceutil/trace.go:171","msg":"trace[1285359531] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"150.909776ms","start":"2026-03-03T13:56:59.639565Z","end":"2026-03-03T13:56:59.790475Z","steps":["trace[1285359531] 'process raft request' (duration: 150.777873ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:56:59.790487Z","caller":"traceutil/trace.go:171","msg":"trace[1760001924] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"150.926706ms","start":"2026-03-03T13:56:59.639545Z","end":"2026-03-03T13:56:59.790472Z","steps":["trace[1760001924] 'process raft request' (duration: 150.66849ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:12.656308Z","caller":"traceutil/trace.go:171","msg":"trace[645685278] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"306.214183ms","start":"2026-03-03T13:57:12.350075Z","end":"2026-03-03T13:57:12.656289Z","steps":["trace[645685278] 'process raft request' (duration: 306.07626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:12.65642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:12.350058Z","time spent":"306.306986ms","remote":"127.0.0.1:59262","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-03-03T13:57:13.160346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.428866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:57:13.160454Z","caller":"traceutil/trace.go:171","msg":"trace[647524764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"386.59177ms","start":"2026-03-03T13:57:12.773838Z","end":"2026-03-03T13:57:13.160429Z","steps":["trace[647524764] 'range keys from in-memory index tree' (duration: 386.327574ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:13.160548Z","caller":"traceutil/trace.go:171","msg":"trace[1875083417] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"362.031109ms","start":"2026-03-03T13:57:12.798489Z","end":"2026-03-03T13:57:13.160521Z","steps":["trace[1875083417] 'process raft request' (duration: 337.880747ms)","trace[1875083417] 'compare' (duration: 23.912737ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:57:13.160669Z","caller":"traceutil/trace.go:171","msg":"trace[1201393700] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"306.142602ms","start":"2026-03-03T13:57:12.854498Z","end":"2026-03-03T13:57:13.160641Z","steps":["trace[1201393700] 'read index received' (duration: 281.883477ms)","trace[1201393700] 'applied index is now lower than readState.Index' (duration: 24.255475ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:57:13.160695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:12.79847Z","time spent":"362.134121ms","remote":"127.0.0.1:59262","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-03-03T13:57:13.160848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.350287ms","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-03-03T13:57:13.160899Z","caller":"traceutil/trace.go:171","msg":"trace[826116698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"306.433588ms","start":"2026-03-03T13:57:12.854452Z","end":"2026-03-03T13:57:13.160886Z","steps":["trace[826116698] 'agreement among raft nodes before linearized reading' (duration: 306.260945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:13.16094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:12.854436Z","time spent":"306.49244ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T13:57:13.16117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:12.77382Z","time spent":"386.779474ms","remote":"127.0.0.1:58962","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-03-03T13:57:18.022903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871139229108101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T13:57:18.523954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871139229108101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T13:57:18.547704Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.361496697s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T13:57:18.548039Z","caller":"traceutil/trace.go:171","msg":"trace[1303135012] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"1.025464111s","start":"2026-03-03T13:57:17.522558Z","end":"2026-03-03T13:57:18.548022Z","steps":["trace[1303135012] 'read index received' (duration: 1.025276497s)","trace[1303135012] 'applied index is now lower than readState.Index' (duration: 186.924µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:57:18.548266Z","caller":"traceutil/trace.go:171","msg":"trace[1180461022] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.362134071s","start":"2026-03-03T13:57:17.186125Z","end":"2026-03-03T13:57:18.548259Z","steps":["trace[1180461022] 'process raft request' (duration: 1.361785943s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:18.548348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:17.186106Z","time spent":"1.362185952s","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T13:57:18.548586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026001994s","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-03-03T13:57:18.548581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.154978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T13:57:18.548634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.682703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T13:57:18.548638Z","caller":"traceutil/trace.go:171","msg":"trace[2034815061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.026097695s","start":"2026-03-03T13:57:17.522524Z","end":"2026-03-03T13:57:18.548622Z","steps":["trace[2034815061] 'agreement among raft nodes before linearized reading' (duration: 1.025924831s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:18.548652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.513652ms","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-03-03T13:57:18.54867Z","caller":"traceutil/trace.go:171","msg":"trace[1154757819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"179.762535ms","start":"2026-03-03T13:57:18.368895Z","end":"2026-03-03T13:57:18.548658Z","steps":["trace[1154757819] 'agreement among raft nodes before linearized reading' (duration: 179.673553ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:18.548677Z","caller":"traceutil/trace.go:171","msg":"trace[50376389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"317.568413ms","start":"2026-03-03T13:57:18.231102Z","end":"2026-03-03T13:57:18.54867Z","steps":["trace[50376389] 'agreement among raft nodes before linearized reading' (duration: 317.476251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:18.548681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:17.522509Z","time spent":"1.026162877s","remote":"127.0.0.1:59262","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-03-03T13:57:18.548698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:18.231086Z","time spent":"317.607834ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T13:57:18.548681Z","caller":"traceutil/trace.go:171","msg":"trace[335279779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"776.29744ms","start":"2026-03-03T13:57:17.772357Z","end":"2026-03-03T13:57:18.548654Z","steps":["trace[335279779] 'agreement among raft nodes before linearized reading' (duration: 776.121606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:18.548743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:17.77234Z","time spent":"776.389463ms","remote":"127.0.0.1:58962","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-03-03T13:57:18.732021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.43251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T13:57:18.73241Z","caller":"traceutil/trace.go:171","msg":"trace[233782166] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"178.487977ms","start":"2026-03-03T13:57:18.553905Z","end":"2026-03-03T13:57:18.732393Z","steps":["trace[233782166] 'process raft request' (duration: 178.200431ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:18.732407Z","caller":"traceutil/trace.go:171","msg":"trace[984809615] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"179.018539ms","start":"2026-03-03T13:57:18.553356Z","end":"2026-03-03T13:57:18.732375Z","steps":["trace[984809615] 'process raft request' (duration: 62.16028ms)","trace[984809615] 'compare' (duration: 116.329967ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:57:19.131115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.915691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T13:57:19.131352Z","caller":"traceutil/trace.go:171","msg":"trace[1529576059] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"394.382037ms","start":"2026-03-03T13:57:18.736936Z","end":"2026-03-03T13:57:19.131318Z","steps":["trace[1529576059] 'process raft request' (duration: 199.174879ms)","trace[1529576059] 'compare' (duration: 194.816939ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:57:19.131463Z","caller":"traceutil/trace.go:171","msg":"trace[1741281458] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"348.501609ms","start":"2026-03-03T13:57:18.782934Z","end":"2026-03-03T13:57:19.131436Z","steps":["trace[1741281458] 'read index received' (duration: 153.188538ms)","trace[1741281458] 'applied index is now lower than readState.Index' (duration: 195.30964ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:57:19.131565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:18.736918Z","time spent":"394.530312ms","remote":"127.0.0.1:59262","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-03-03T13:57:19.14841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.458088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:57:19.148498Z","caller":"traceutil/trace.go:171","msg":"trace[1643460966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"365.57174ms","start":"2026-03-03T13:57:18.7829Z","end":"2026-03-03T13:57:19.148472Z","steps":["trace[1643460966] 'agreement among raft nodes before linearized reading' (duration: 348.638843ms)","trace[1643460966] 'range keys from in-memory index tree' (duration: 16.820235ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:57:19.148577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:18.78288Z","time spent":"365.682033ms","remote":"127.0.0.1:58962","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-03-03T13:57:21.066034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871139229108115,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T13:57:21.138487Z","caller":"traceutil/trace.go:171","msg":"trace[2095141683] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"572.875455ms","start":"2026-03-03T13:57:20.565565Z","end":"2026-03-03T13:57:21.138441Z","steps":["trace[2095141683] 'read index received' (duration: 572.681121ms)","trace[2095141683] 'applied index is now lower than readState.Index' (duration: 193.624µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:57:21.138518Z","caller":"traceutil/trace.go:171","msg":"trace[681739965] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"581.03902ms","start":"2026-03-03T13:57:20.557452Z","end":"2026-03-03T13:57:21.138491Z","steps":["trace[681739965] 'process raft request' (duration: 580.842666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:21.138651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:20.557434Z","time spent":"581.142373ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T13:57:21.138715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.469633ms","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-03-03T13:57:21.138738Z","caller":"traceutil/trace.go:171","msg":"trace[1841259221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"402.590816ms","start":"2026-03-03T13:57:20.736139Z","end":"2026-03-03T13:57:21.13873Z","steps":["trace[1841259221] 'agreement among raft nodes before linearized reading' (duration: 402.543615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:21.138763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:20.736119Z","time spent":"402.638977ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T13:57:21.1388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.669035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T13:57:21.138833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.464734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:57:21.138848Z","caller":"traceutil/trace.go:171","msg":"trace[1317727221] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:687; }","duration":"317.720786ms","start":"2026-03-03T13:57:20.821113Z","end":"2026-03-03T13:57:21.138834Z","steps":["trace[1317727221] 'agreement among raft nodes before linearized reading' (duration: 317.641514ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:21.138878Z","caller":"traceutil/trace.go:171","msg":"trace[1260192048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"364.569357ms","start":"2026-03-03T13:57:20.774295Z","end":"2026-03-03T13:57:21.138865Z","steps":["trace[1260192048] 'agreement among raft nodes before linearized reading' (duration: 364.465404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:21.138921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:20.774279Z","time spent":"364.633048ms","remote":"127.0.0.1:58954","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-03-03T13:57:21.138972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.95105ms","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-03-03T13:57:21.138993Z","caller":"traceutil/trace.go:171","msg":"trace[1784194265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"402.030453ms","start":"2026-03-03T13:57:20.736956Z","end":"2026-03-03T13:57:21.138987Z","steps":["trace[1784194265] 'agreement among raft nodes before linearized reading' (duration: 401.949051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:21.13901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:20.73694Z","time spent":"402.066233ms","remote":"127.0.0.1:59262","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-03-03T13:57:21.138886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:20.821067Z","time spent":"317.808508ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-03T13:57:21.138662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.07614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T13:57:21.139239Z","caller":"traceutil/trace.go:171","msg":"trace[1122119517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"573.688034ms","start":"2026-03-03T13:57:20.565539Z","end":"2026-03-03T13:57:21.139227Z","steps":["trace[1122119517] 'agreement among raft nodes before linearized reading' (duration: 573.022729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:21.139276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:20.565528Z","time spent":"573.736205ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T13:57:23.264834Z","caller":"traceutil/trace.go:171","msg":"trace[31530696] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"109.868195ms","start":"2026-03-03T13:57:23.154941Z","end":"2026-03-03T13:57:23.264809Z","steps":["trace[31530696] 'process raft request' (duration: 109.68778ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:23.265806Z","caller":"traceutil/trace.go:171","msg":"trace[133632861] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"110.746906ms","start":"2026-03-03T13:57:23.155019Z","end":"2026-03-03T13:57:23.265766Z","steps":["trace[133632861] 'process raft request' (duration: 110.518531ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:23.266293Z","caller":"traceutil/trace.go:171","msg":"trace[1727088261] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"111.25201ms","start":"2026-03-03T13:57:23.155019Z","end":"2026-03-03T13:57:23.266271Z","steps":["trace[1727088261] 'process raft request' (duration: 110.689695ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:23.266519Z","caller":"traceutil/trace.go:171","msg":"trace[922973655] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"110.697846ms","start":"2026-03-03T13:57:23.1558Z","end":"2026-03-03T13:57:23.266498Z","steps":["trace[922973655] 'process raft request' (duration: 110.324636ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:23.268301Z","caller":"traceutil/trace.go:171","msg":"trace[813718660] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"111.595438ms","start":"2026-03-03T13:57:23.156684Z","end":"2026-03-03T13:57:23.268279Z","steps":["trace[813718660] 'process raft request' (duration: 109.614819ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:43.442427Z","caller":"traceutil/trace.go:171","msg":"trace[1872321716] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"115.861694ms","start":"2026-03-03T13:57:43.326531Z","end":"2026-03-03T13:57:43.442392Z","steps":["trace[1872321716] 'read index received' (duration: 115.578297ms)","trace[1872321716] 'applied index is now lower than readState.Index' (duration: 281.327µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:57:43.442665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.095001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T13:57:43.442773Z","caller":"traceutil/trace.go:171","msg":"trace[339378271] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:753; }","duration":"116.219924ms","start":"2026-03-03T13:57:43.326524Z","end":"2026-03-03T13:57:43.442744Z","steps":["trace[339378271] 'agreement among raft nodes before linearized reading' (duration: 116.019299ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:43.583517Z","caller":"traceutil/trace.go:171","msg":"trace[1834059872] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"135.479431ms","start":"2026-03-03T13:57:43.448007Z","end":"2026-03-03T13:57:43.583486Z","steps":["trace[1834059872] 'process raft request' (duration: 127.360139ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:43.583945Z","caller":"traceutil/trace.go:171","msg":"trace[74923581] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"135.859481ms","start":"2026-03-03T13:57:43.448069Z","end":"2026-03-03T13:57:43.583928Z","steps":["trace[74923581] 'process raft request' (duration: 135.372949ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:43.584065Z","caller":"traceutil/trace.go:171","msg":"trace[1363942223] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"134.616059ms","start":"2026-03-03T13:57:43.44942Z","end":"2026-03-03T13:57:43.584036Z","steps":["trace[1363942223] 'process raft request' (duration: 134.531007ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:43.584119Z","caller":"traceutil/trace.go:171","msg":"trace[79788265] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:785; }","duration":"121.78588ms","start":"2026-03-03T13:57:43.462315Z","end":"2026-03-03T13:57:43.584101Z","steps":["trace[79788265] 'read index received' (duration: 112.980551ms)","trace[79788265] 'applied index is now lower than readState.Index' (duration: 8.804289ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:57:43.584291Z","caller":"traceutil/trace.go:171","msg":"trace[1204743263] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"136.138887ms","start":"2026-03-03T13:57:43.448142Z","end":"2026-03-03T13:57:43.584281Z","steps":["trace[1204743263] 'process raft request' (duration: 135.537633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:43.584332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.982305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-03T13:57:43.584379Z","caller":"traceutil/trace.go:171","msg":"trace[144530972] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:759; }","duration":"122.092117ms","start":"2026-03-03T13:57:43.462273Z","end":"2026-03-03T13:57:43.584365Z","steps":["trace[144530972] 'agreement among raft nodes before linearized reading' (duration: 121.948964ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:43.584508Z","caller":"traceutil/trace.go:171","msg":"trace[2144791709] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"134.681132ms","start":"2026-03-03T13:57:43.449813Z","end":"2026-03-03T13:57:43.584494Z","steps":["trace[2144791709] 'process raft request' (duration: 134.185949ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:45.880713Z","caller":"traceutil/trace.go:171","msg":"trace[967025171] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"281.084024ms","start":"2026-03-03T13:57:45.599585Z","end":"2026-03-03T13:57:45.880669Z","steps":["trace[967025171] 'process raft request' (duration: 280.852858ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:46.44015Z","caller":"traceutil/trace.go:171","msg":"trace[609772042] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"840.473007ms","start":"2026-03-03T13:57:45.599648Z","end":"2026-03-03T13:57:46.440121Z","steps":["trace[609772042] 'process raft request' (duration: 840.211521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:46.440366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:45.599623Z","time spent":"840.646542ms","remote":"127.0.0.1:59262","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-03-03T13:57:46.440463Z","caller":"traceutil/trace.go:171","msg":"trace[726819882] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"838.439687ms","start":"2026-03-03T13:57:45.602001Z","end":"2026-03-03T13:57:46.440441Z","steps":["trace[726819882] 'process raft request' (duration: 838.303244ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:46.440495Z","caller":"traceutil/trace.go:171","msg":"trace[1057397681] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"840.060578ms","start":"2026-03-03T13:57:45.600415Z","end":"2026-03-03T13:57:46.440476Z","steps":["trace[1057397681] 'process raft request' (duration: 839.658868ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:46.440611Z","caller":"traceutil/trace.go:171","msg":"trace[578436911] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"838.093138ms","start":"2026-03-03T13:57:45.602506Z","end":"2026-03-03T13:57:46.440599Z","steps":["trace[578436911] 'process raft request' (duration: 837.900993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:46.440613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:45.601996Z","time spent":"838.538439ms","remote":"127.0.0.1:59262","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-03-03T13:57:46.440628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:45.600406Z","time spent":"840.185501ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T13:57:46.440687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:45.6025Z","time spent":"838.146069ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T13:57:46.827259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.618546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:57:46.827433Z","caller":"traceutil/trace.go:171","msg":"trace[244593155] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:765; }","duration":"115.848011ms","start":"2026-03-03T13:57:46.711565Z","end":"2026-03-03T13:57:46.827413Z","steps":["trace[244593155] 'count revisions from in-memory index tree' (duration: 115.503143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:48.557614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.289035ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T13:57:48.557773Z","caller":"traceutil/trace.go:171","msg":"trace[1526628015] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"112.444388ms","start":"2026-03-03T13:57:48.445305Z","end":"2026-03-03T13:57:48.55775Z","steps":["trace[1526628015] 'read index received' (duration: 49.071µs)","trace[1526628015] 'applied index is now lower than readState.Index' (duration: 112.393247ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:57:48.55794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.617023ms","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-03-03T13:57:48.558023Z","caller":"traceutil/trace.go:171","msg":"trace[1396324385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"112.751276ms","start":"2026-03-03T13:57:48.445252Z","end":"2026-03-03T13:57:48.558003Z","steps":["trace[1396324385] 'agreement among raft nodes before linearized reading' (duration: 112.50775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:48.558039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.292555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-03T13:57:48.55806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.739706ms","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-03-03T13:57:48.5581Z","caller":"traceutil/trace.go:171","msg":"trace[1486467832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:767; }","duration":"112.379706ms","start":"2026-03-03T13:57:48.445703Z","end":"2026-03-03T13:57:48.558083Z","steps":["trace[1486467832] 'agreement among raft nodes before linearized reading' (duration: 112.202182ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:48.558155Z","caller":"traceutil/trace.go:171","msg":"trace[1460303303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:767; }","duration":"112.87878ms","start":"2026-03-03T13:57:48.445251Z","end":"2026-03-03T13:57:48.55813Z","steps":["trace[1460303303] 'agreement among raft nodes before linearized reading' (duration: 112.608163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:48.558136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.155612ms","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-03-03T13:57:48.558229Z","caller":"traceutil/trace.go:171","msg":"trace[1579554694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:767; }","duration":"112.306365ms","start":"2026-03-03T13:57:48.445899Z","end":"2026-03-03T13:57:48.558205Z","steps":["trace[1579554694] 'agreement among raft nodes before linearized reading' (duration: 112.006358ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:48.986163Z","caller":"traceutil/trace.go:171","msg":"trace[260070750] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"422.907215ms","start":"2026-03-03T13:57:48.563221Z","end":"2026-03-03T13:57:48.986128Z","steps":["trace[260070750] 'process raft request' (duration: 422.69325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:48.986366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:48.5632Z","time spent":"423.055478ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T13:57:49.124613Z","caller":"traceutil/trace.go:171","msg":"trace[288431260] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"560.790536ms","start":"2026-03-03T13:57:48.563776Z","end":"2026-03-03T13:57:49.124567Z","steps":["trace[288431260] 'process raft request' (duration: 538.092241ms)","trace[288431260] 'compare' (duration: 22.409098ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:57:49.124618Z","caller":"traceutil/trace.go:171","msg":"trace[2060858034] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"560.665093ms","start":"2026-03-03T13:57:48.56392Z","end":"2026-03-03T13:57:49.124585Z","steps":["trace[2060858034] 'process raft request' (duration: 560.555851ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:49.124822Z","caller":"traceutil/trace.go:171","msg":"trace[714706626] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:799; }","duration":"349.414966ms","start":"2026-03-03T13:57:48.775378Z","end":"2026-03-03T13:57:49.124793Z","steps":["trace[714706626] 'read index received' (duration: 210.601872ms)","trace[714706626] 'applied index is now lower than readState.Index' (duration: 138.810414ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:57:49.124852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:48.56388Z","time spent":"560.845908ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T13:57:49.124886Z","caller":"traceutil/trace.go:171","msg":"trace[273203131] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"560.788416ms","start":"2026-03-03T13:57:48.564058Z","end":"2026-03-03T13:57:49.124846Z","steps":["trace[273203131] 'process raft request' (duration: 560.476928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:49.124939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:48.563761Z","time spent":"560.95197ms","remote":"127.0.0.1:59262","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-03-03T13:57:49.125017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.631141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T13:57:49.125074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:48.564041Z","time spent":"560.94016ms","remote":"127.0.0.1:59262","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-03-03T13:57:49.125098Z","caller":"traceutil/trace.go:171","msg":"trace[482720826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"349.719773ms","start":"2026-03-03T13:57:48.775354Z","end":"2026-03-03T13:57:49.125074Z","steps":["trace[482720826] 'agreement among raft nodes before linearized reading' (duration: 349.5819ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:57:49.125144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:57:48.775279Z","time spent":"349.857047ms","remote":"127.0.0.1:58962","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-03-03T13:57:59.37402Z","caller":"traceutil/trace.go:171","msg":"trace[2066847008] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"117.354801ms","start":"2026-03-03T13:57:59.256639Z","end":"2026-03-03T13:57:59.373994Z","steps":["trace[2066847008] 'process raft request' (duration: 117.217278ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:57:59.441246Z","caller":"traceutil/trace.go:171","msg":"trace[1033472972] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"184.120823ms","start":"2026-03-03T13:57:59.257105Z","end":"2026-03-03T13:57:59.441226Z","steps":["trace[1033472972] 'process raft request' (duration: 184.02156ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:00.094414Z","caller":"traceutil/trace.go:171","msg":"trace[1503283651] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"110.398538ms","start":"2026-03-03T13:57:59.983986Z","end":"2026-03-03T13:58:00.094384Z","steps":["trace[1503283651] 'process raft request' (duration: 106.770058ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:00.402171Z","caller":"traceutil/trace.go:171","msg":"trace[1578399128] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"113.598428ms","start":"2026-03-03T13:58:00.288532Z","end":"2026-03-03T13:58:00.402131Z","steps":["trace[1578399128] 'process raft request' (duration: 48.206249ms)","trace[1578399128] 'compare' (duration: 65.196684ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:00.67941Z","caller":"traceutil/trace.go:171","msg":"trace[1842018832] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"128.259563ms","start":"2026-03-03T13:58:00.551101Z","end":"2026-03-03T13:58:00.679361Z","steps":["trace[1842018832] 'process raft request' (duration: 110.286755ms)","trace[1842018832] 'compare' (duration: 17.720302ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:00.679695Z","caller":"traceutil/trace.go:171","msg":"trace[1885483039] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"124.93928ms","start":"2026-03-03T13:58:00.554727Z","end":"2026-03-03T13:58:00.679667Z","steps":["trace[1885483039] 'process raft request' (duration: 124.577722ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:00.679413Z","caller":"traceutil/trace.go:171","msg":"trace[1301135562] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"127.869184ms","start":"2026-03-03T13:58:00.551515Z","end":"2026-03-03T13:58:00.679385Z","steps":["trace[1301135562] 'process raft request' (duration: 127.7225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:00.679837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.925125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-03-03T13:58:00.67989Z","caller":"traceutil/trace.go:171","msg":"trace[1622371374] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:839; }","duration":"122.013608ms","start":"2026-03-03T13:58:00.557869Z","end":"2026-03-03T13:58:00.679882Z","steps":["trace[1622371374] 'agreement among raft nodes before linearized reading' (duration: 121.873574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:00.70571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.899531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363690"} {"level":"info","ts":"2026-03-03T13:58:00.705881Z","caller":"traceutil/trace.go:171","msg":"trace[1535829447] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:840; }","duration":"105.068474ms","start":"2026-03-03T13:58:00.600757Z","end":"2026-03-03T13:58:00.705825Z","steps":["trace[1535829447] 'agreement among raft nodes before linearized reading' (duration: 104.670704ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:00.706002Z","caller":"traceutil/trace.go:171","msg":"trace[256188174] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"102.199663ms","start":"2026-03-03T13:58:00.603579Z","end":"2026-03-03T13:58:00.705778Z","steps":["trace[256188174] 'process raft request' (duration: 101.720171ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:00.902897Z","caller":"traceutil/trace.go:171","msg":"trace[1746939932] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"133.297908ms","start":"2026-03-03T13:58:00.769572Z","end":"2026-03-03T13:58:00.902869Z","steps":["trace[1746939932] 'read index received' (duration: 61.655785ms)","trace[1746939932] 'applied index is now lower than readState.Index' (duration: 71.640853ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:00.903034Z","caller":"traceutil/trace.go:171","msg":"trace[805681432] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"135.940304ms","start":"2026-03-03T13:58:00.76705Z","end":"2026-03-03T13:58:00.90299Z","steps":["trace[805681432] 'process raft request' (duration: 64.169227ms)","trace[805681432] 'compare' (duration: 71.539941ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:00.903164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.557125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6\" ","response":"range_response_count:1 size:10888"} {"level":"info","ts":"2026-03-03T13:58:00.90323Z","caller":"traceutil/trace.go:171","msg":"trace[348420125] range","detail":"{range_begin:/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6; range_end:; response_count:1; response_revision:846; }","duration":"133.680858ms","start":"2026-03-03T13:58:00.769532Z","end":"2026-03-03T13:58:00.903213Z","steps":["trace[348420125] 'agreement among raft nodes before linearized reading' (duration: 133.462132ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:00.90961Z","caller":"traceutil/trace.go:171","msg":"trace[2000910911] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"135.921893ms","start":"2026-03-03T13:58:00.773675Z","end":"2026-03-03T13:58:00.909597Z","steps":["trace[2000910911] 'process raft request' (duration: 135.571255ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:08.950884Z","caller":"traceutil/trace.go:171","msg":"trace[1701767767] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"765.610946ms","start":"2026-03-03T13:58:08.185242Z","end":"2026-03-03T13:58:08.950853Z","steps":["trace[1701767767] 'process raft request' (duration: 765.412571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:08.951092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:08.185229Z","time spent":"765.757899ms","remote":"127.0.0.1:59262","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-03-03T13:58:09.829157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.47368ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T13:58:09.829258Z","caller":"traceutil/trace.go:171","msg":"trace[769400866] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"1.054199628s","start":"2026-03-03T13:58:08.775039Z","end":"2026-03-03T13:58:09.829239Z","steps":["trace[769400866] 'read index received' (duration: 176.643746ms)","trace[769400866] 'applied index is now lower than readState.Index' (duration: 877.554212ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:09.829407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054358193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:09.829433Z","caller":"traceutil/trace.go:171","msg":"trace[1895335264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"1.054392534s","start":"2026-03-03T13:58:08.775031Z","end":"2026-03-03T13:58:09.829424Z","steps":["trace[1895335264] 'agreement among raft nodes before linearized reading' (duration: 1.054329132s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:09.829469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:08.774963Z","time spent":"1.054499696s","remote":"127.0.0.1:58962","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-03-03T13:58:09.829619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.250428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T13:58:09.829785Z","caller":"traceutil/trace.go:171","msg":"trace[1667432772] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:874; }","duration":"716.432413ms","start":"2026-03-03T13:58:09.11328Z","end":"2026-03-03T13:58:09.829713Z","steps":["trace[1667432772] 'agreement among raft nodes before linearized reading' (duration: 716.203607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:09.829854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:09.11326Z","time spent":"716.574696ms","remote":"127.0.0.1:59446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-03T13:58:09.829917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.450237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-03T13:58:09.829928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.458255ms","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-03-03T13:58:09.829954Z","caller":"traceutil/trace.go:171","msg":"trace[110292284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:874; }","duration":"222.536229ms","start":"2026-03-03T13:58:09.607408Z","end":"2026-03-03T13:58:09.829944Z","steps":["trace[110292284] 'agreement among raft nodes before linearized reading' (duration: 222.407585ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:09.829976Z","caller":"traceutil/trace.go:171","msg":"trace[105476216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:874; }","duration":"475.551177ms","start":"2026-03-03T13:58:09.354408Z","end":"2026-03-03T13:58:09.829959Z","steps":["trace[105476216] 'agreement among raft nodes before linearized reading' (duration: 475.411913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:09.829963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.709344ms","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-03-03T13:58:09.830006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:09.35438Z","time spent":"475.619849ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T13:58:09.830048Z","caller":"traceutil/trace.go:171","msg":"trace[948639384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:874; }","duration":"390.864869ms","start":"2026-03-03T13:58:09.439163Z","end":"2026-03-03T13:58:09.830028Z","steps":["trace[948639384] 'agreement among raft nodes before linearized reading' (duration: 390.586742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:09.830096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:09.439128Z","time spent":"390.959361ms","remote":"127.0.0.1:59262","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-03-03T13:58:09.830139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.31243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T13:58:09.830165Z","caller":"traceutil/trace.go:171","msg":"trace[1424644975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:874; }","duration":"561.362812ms","start":"2026-03-03T13:58:09.268793Z","end":"2026-03-03T13:58:09.830156Z","steps":["trace[1424644975] 'agreement among raft nodes before linearized reading' (duration: 561.29013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:09.830189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:09.268776Z","time spent":"561.407873ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-03T13:58:10.09222Z","caller":"traceutil/trace.go:171","msg":"trace[1053354440] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"255.061608ms","start":"2026-03-03T13:58:09.837139Z","end":"2026-03-03T13:58:10.0922Z","steps":["trace[1053354440] 'process raft request' (duration: 254.917145ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:10.126002Z","caller":"traceutil/trace.go:171","msg":"trace[1959946317] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:912; }","duration":"280.274326ms","start":"2026-03-03T13:58:09.845709Z","end":"2026-03-03T13:58:10.125984Z","steps":["trace[1959946317] 'read index received' (duration: 246.27376ms)","trace[1959946317] 'applied index is now lower than readState.Index' (duration: 33.999826ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:10.126624Z","caller":"traceutil/trace.go:171","msg":"trace[2048118827] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"289.001133ms","start":"2026-03-03T13:58:09.837572Z","end":"2026-03-03T13:58:10.126573Z","steps":["trace[2048118827] 'process raft request' (duration: 288.225874ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:10.126427Z","caller":"traceutil/trace.go:171","msg":"trace[1944807127] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"288.438459ms","start":"2026-03-03T13:58:09.837586Z","end":"2026-03-03T13:58:10.126024Z","steps":["trace[1944807127] 'process raft request' (duration: 288.340617ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:10.126804Z","caller":"traceutil/trace.go:171","msg":"trace[1243645169] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"287.532306ms","start":"2026-03-03T13:58:09.83854Z","end":"2026-03-03T13:58:10.126073Z","steps":["trace[1243645169] 'process raft request' (duration: 287.414054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:10.127049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.321702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:10.127507Z","caller":"traceutil/trace.go:171","msg":"trace[458146419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"281.803724ms","start":"2026-03-03T13:58:09.845669Z","end":"2026-03-03T13:58:10.127472Z","steps":["trace[458146419] 'agreement among raft nodes before linearized reading' (duration: 281.039935ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:12.213116Z","caller":"traceutil/trace.go:171","msg":"trace[117563792] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"112.469489ms","start":"2026-03-03T13:58:12.100632Z","end":"2026-03-03T13:58:12.213101Z","steps":["trace[117563792] 'process raft request' (duration: 112.346956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:24.920386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871139229108705,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T13:58:25.19197Z","caller":"traceutil/trace.go:171","msg":"trace[470105225] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"826.899884ms","start":"2026-03-03T13:58:24.365046Z","end":"2026-03-03T13:58:25.191946Z","steps":["trace[470105225] 'process raft request' (duration: 826.783222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:25.192375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:24.36502Z","time spent":"827.022357ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T13:58:26.272479Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080643565s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T13:58:26.272913Z","caller":"traceutil/trace.go:171","msg":"trace[1067435562] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:954; }","duration":"1.853173238s","start":"2026-03-03T13:58:24.419717Z","end":"2026-03-03T13:58:26.27289Z","steps":["trace[1067435562] 'read index received' (duration: 772.111924ms)","trace[1067435562] 'applied index is now lower than readState.Index' (duration: 1.081060454s)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:26.273027Z","caller":"traceutil/trace.go:171","msg":"trace[1843305552] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"1.563496539s","start":"2026-03-03T13:58:24.709501Z","end":"2026-03-03T13:58:26.272997Z","steps":["trace[1843305552] 'process raft request' (duration: 1.563220183s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:26.27317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:24.709479Z","time spent":"1.563611051s","remote":"127.0.0.1:59262","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-03-03T13:58:26.273182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.832133526s","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-03-03T13:58:26.273211Z","caller":"traceutil/trace.go:171","msg":"trace[1158891463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"1.832206279s","start":"2026-03-03T13:58:24.440995Z","end":"2026-03-03T13:58:26.273201Z","steps":["trace[1158891463] 'agreement among raft nodes before linearized reading' (duration: 1.832061275s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:26.273238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:24.440983Z","time spent":"1.832248909s","remote":"127.0.0.1:59262","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-03-03T13:58:26.273223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.08137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T13:58:26.273268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499404451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:26.273316Z","caller":"traceutil/trace.go:171","msg":"trace[1644359599] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:915; }","duration":"759.234064ms","start":"2026-03-03T13:58:25.51406Z","end":"2026-03-03T13:58:26.273294Z","steps":["trace[1644359599] 'agreement among raft nodes before linearized reading' (duration: 759.039389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:26.273348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.731969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-03T13:58:26.273457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:25.514044Z","time spent":"759.385557ms","remote":"127.0.0.1:59228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-03T13:58:26.273454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143558535s","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-03-03T13:58:26.273475Z","caller":"traceutil/trace.go:171","msg":"trace[66448703] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:915; }","duration":"245.939544ms","start":"2026-03-03T13:58:26.027509Z","end":"2026-03-03T13:58:26.273448Z","steps":["trace[66448703] 'agreement among raft nodes before linearized reading' (duration: 245.677938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:26.273357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.83227641s","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-03-03T13:58:26.273525Z","caller":"traceutil/trace.go:171","msg":"trace[1337485162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:915; }","duration":"1.143624616s","start":"2026-03-03T13:58:25.129876Z","end":"2026-03-03T13:58:26.2735Z","steps":["trace[1337485162] 'agreement among raft nodes before linearized reading' (duration: 1.143380241s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:26.273574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:25.129815Z","time spent":"1.143748689s","remote":"127.0.0.1:59262","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-03-03T13:58:26.273604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.853762633s","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-03-03T13:58:26.273532Z","caller":"traceutil/trace.go:171","msg":"trace[1052988856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"1.832459834s","start":"2026-03-03T13:58:24.44106Z","end":"2026-03-03T13:58:26.273519Z","steps":["trace[1052988856] 'agreement among raft nodes before linearized reading' (duration: 1.832207758s)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:26.273637Z","caller":"traceutil/trace.go:171","msg":"trace[2007380112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"1.853950146s","start":"2026-03-03T13:58:24.419678Z","end":"2026-03-03T13:58:26.273628Z","steps":["trace[2007380112] 'agreement among raft nodes before linearized reading' (duration: 1.853349383s)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:26.273354Z","caller":"traceutil/trace.go:171","msg":"trace[1337171003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"1.499544844s","start":"2026-03-03T13:58:24.773785Z","end":"2026-03-03T13:58:26.27333Z","steps":["trace[1337171003] 'agreement among raft nodes before linearized reading' (duration: 1.499389481s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:26.273755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:24.441054Z","time spent":"1.832654479s","remote":"127.0.0.1:59262","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-03-03T13:58:26.273743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:24.419657Z","time spent":"1.85407632s","remote":"127.0.0.1:59262","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-03-03T13:58:26.273811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:24.773771Z","time spent":"1.500024285s","remote":"127.0.0.1:58962","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-03-03T13:58:27.317586Z","caller":"traceutil/trace.go:171","msg":"trace[1497017034] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"119.069967ms","start":"2026-03-03T13:58:27.198483Z","end":"2026-03-03T13:58:27.317553Z","steps":["trace[1497017034] 'read index received' (duration: 118.810841ms)","trace[1497017034] 'applied index is now lower than readState.Index' (duration: 257.656µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:27.317932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.408506ms","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-03-03T13:58:27.31798Z","caller":"traceutil/trace.go:171","msg":"trace[409442530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"119.526808ms","start":"2026-03-03T13:58:27.198441Z","end":"2026-03-03T13:58:27.317968Z","steps":["trace[409442530] 'agreement among raft nodes before linearized reading' (duration: 119.256112ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:27.318343Z","caller":"traceutil/trace.go:171","msg":"trace[1864879684] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"121.236437ms","start":"2026-03-03T13:58:27.197092Z","end":"2026-03-03T13:58:27.318328Z","steps":["trace[1864879684] 'process raft request' (duration: 120.303096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:30.021284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.796095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T13:58:30.021429Z","caller":"traceutil/trace.go:171","msg":"trace[1204294049] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"227.053179ms","start":"2026-03-03T13:58:29.794354Z","end":"2026-03-03T13:58:30.021407Z","steps":["trace[1204294049] 'process raft request' (duration: 110.005658ms)","trace[1204294049] 'compare' (duration: 116.625861ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:30.57652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.158019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T13:58:30.576729Z","caller":"traceutil/trace.go:171","msg":"trace[1973807458] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"781.169194ms","start":"2026-03-03T13:58:29.795536Z","end":"2026-03-03T13:58:30.576705Z","steps":["trace[1973807458] 'process raft request' (duration: 518.656146ms)","trace[1973807458] 'compare' (duration: 261.790831ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:30.576756Z","caller":"traceutil/trace.go:171","msg":"trace[1611340999] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:986; }","duration":"500.761244ms","start":"2026-03-03T13:58:30.07598Z","end":"2026-03-03T13:58:30.576741Z","steps":["trace[1611340999] 'read index received' (duration: 238.120814ms)","trace[1611340999] 'applied index is now lower than readState.Index' (duration: 262.63948ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:30.576802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:29.795515Z","time spent":"781.252856ms","remote":"127.0.0.1:59276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T13:58:30.576873Z","caller":"traceutil/trace.go:171","msg":"trace[963484268] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"775.447772ms","start":"2026-03-03T13:58:29.801392Z","end":"2026-03-03T13:58:30.57684Z","steps":["trace[963484268] 'process raft request' (duration: 775.252097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:30.576924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.946048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T13:58:30.576964Z","caller":"traceutil/trace.go:171","msg":"trace[197821750] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:946; }","duration":"500.985059ms","start":"2026-03-03T13:58:30.075962Z","end":"2026-03-03T13:58:30.576947Z","steps":["trace[197821750] 'agreement among raft nodes before linearized reading' (duration: 500.883747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:30.577002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:30.075906Z","time spent":"501.086001ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-03-03T13:58:30.577034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:29.80136Z","time spent":"775.583485ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T13:58:30.577241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.523109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:30.577278Z","caller":"traceutil/trace.go:171","msg":"trace[122636292] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:946; }","duration":"253.6027ms","start":"2026-03-03T13:58:30.323663Z","end":"2026-03-03T13:58:30.577266Z","steps":["trace[122636292] 'agreement among raft nodes before linearized reading' (duration: 253.549639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:30.577386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.928221ms","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-03-03T13:58:30.577411Z","caller":"traceutil/trace.go:171","msg":"trace[1711746313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"228.978212ms","start":"2026-03-03T13:58:30.348425Z","end":"2026-03-03T13:58:30.577403Z","steps":["trace[1711746313] 'agreement among raft nodes before linearized reading' (duration: 228.86227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:30.577444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.135426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T13:58:30.57747Z","caller":"traceutil/trace.go:171","msg":"trace[1849130780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"229.206078ms","start":"2026-03-03T13:58:30.348256Z","end":"2026-03-03T13:58:30.577462Z","steps":["trace[1849130780] 'agreement among raft nodes before linearized reading' (duration: 229.091755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:30.577472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.511378ms","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-03-03T13:58:30.577498Z","caller":"traceutil/trace.go:171","msg":"trace[1698435991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"233.552628ms","start":"2026-03-03T13:58:30.343937Z","end":"2026-03-03T13:58:30.577489Z","steps":["trace[1698435991] 'agreement among raft nodes before linearized reading' (duration: 233.467776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:30.577518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.442299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:30.577534Z","caller":"traceutil/trace.go:171","msg":"trace[27274301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"130.49438ms","start":"2026-03-03T13:58:30.447034Z","end":"2026-03-03T13:58:30.577529Z","steps":["trace[27274301] 'agreement among raft nodes before linearized reading' (duration: 130.46703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:30.577683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.238364ms","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-03-03T13:58:30.577699Z","caller":"traceutil/trace.go:171","msg":"trace[1620158443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"211.296305ms","start":"2026-03-03T13:58:30.366398Z","end":"2026-03-03T13:58:30.577694Z","steps":["trace[1620158443] 'agreement among raft nodes before linearized reading' (duration: 211.246424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:31.993729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.145337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T13:58:31.994033Z","caller":"traceutil/trace.go:171","msg":"trace[389108822] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:988; }","duration":"1.220231764s","start":"2026-03-03T13:58:30.77378Z","end":"2026-03-03T13:58:31.994012Z","steps":["trace[389108822] 'read index received' (duration: 427.659348ms)","trace[389108822] 'applied index is now lower than readState.Index' (duration: 792.570956ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:31.994143Z","caller":"traceutil/trace.go:171","msg":"trace[786829070] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.408233402s","start":"2026-03-03T13:58:30.5859Z","end":"2026-03-03T13:58:31.994133Z","steps":["trace[786829070] 'process raft request' (duration: 1.408037598s)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:31.994179Z","caller":"traceutil/trace.go:171","msg":"trace[602767288] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.408562779s","start":"2026-03-03T13:58:30.585607Z","end":"2026-03-03T13:58:31.99417Z","steps":["trace[602767288] 'process raft request' (duration: 615.864599ms)","trace[602767288] 'compare' (duration: 791.976654ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:31.994217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:30.585894Z","time spent":"1.408286073s","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T13:58:31.994247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:30.585583Z","time spent":"1.408628111s","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T13:58:31.994141Z","caller":"traceutil/trace.go:171","msg":"trace[72539309] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.408313864s","start":"2026-03-03T13:58:30.585808Z","end":"2026-03-03T13:58:31.994122Z","steps":["trace[72539309] 'process raft request' (duration: 1.408057538s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:31.99429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22048576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:31.994349Z","caller":"traceutil/trace.go:171","msg":"trace[918698792] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"1.399676954s","start":"2026-03-03T13:58:30.594664Z","end":"2026-03-03T13:58:31.994341Z","steps":["trace[918698792] 'process raft request' (duration: 1.399310056s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:31.994374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:30.585797Z","time spent":"1.408547679s","remote":"127.0.0.1:59262","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-03-03T13:58:31.994388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:30.594629Z","time spent":"1.399736916s","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T13:58:31.994383Z","caller":"traceutil/trace.go:171","msg":"trace[847803109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"1.220593122s","start":"2026-03-03T13:58:30.773762Z","end":"2026-03-03T13:58:31.994356Z","steps":["trace[847803109] 'agreement among raft nodes before linearized reading' (duration: 1.220402948s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:31.994434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:30.773682Z","time spent":"1.220743426s","remote":"127.0.0.1:58954","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-03-03T13:58:31.994421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.522469ms","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-03-03T13:58:31.994524Z","caller":"traceutil/trace.go:171","msg":"trace[1026985313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:950; }","duration":"533.680933ms","start":"2026-03-03T13:58:31.46082Z","end":"2026-03-03T13:58:31.994501Z","steps":["trace[1026985313] 'agreement among raft nodes before linearized reading' (duration: 533.395686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:31.994562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:31.460783Z","time spent":"533.771595ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T13:58:32.650701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.950805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T13:58:32.650846Z","caller":"traceutil/trace.go:171","msg":"trace[1751190823] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"648.984404ms","start":"2026-03-03T13:58:32.001837Z","end":"2026-03-03T13:58:32.650822Z","steps":["trace[1751190823] 'read index received' (duration: 314.68477ms)","trace[1751190823] 'applied index is now lower than readState.Index' (duration: 334.297574ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:32.650931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.093437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:32.650981Z","caller":"traceutil/trace.go:171","msg":"trace[501707538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"649.181899ms","start":"2026-03-03T13:58:32.001785Z","end":"2026-03-03T13:58:32.650967Z","steps":["trace[501707538] 'agreement among raft nodes before linearized reading' (duration: 649.094257ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:32.650957Z","caller":"traceutil/trace.go:171","msg":"trace[762296410] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"651.883791ms","start":"2026-03-03T13:58:31.999033Z","end":"2026-03-03T13:58:32.650917Z","steps":["trace[762296410] 'process raft request' (duration: 317.589767ms)","trace[762296410] 'compare' (duration: 333.69336ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:32.651022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:32.001763Z","time spent":"649.250521ms","remote":"127.0.0.1:58962","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-03-03T13:58:32.651169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:31.999005Z","time spent":"652.036604ms","remote":"127.0.0.1:59262","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-03-03T13:58:32.967826Z","caller":"traceutil/trace.go:171","msg":"trace[656150709] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"110.874509ms","start":"2026-03-03T13:58:32.856913Z","end":"2026-03-03T13:58:32.967787Z","steps":["trace[656150709] 'process raft request' (duration: 110.676824ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:33.508261Z","caller":"traceutil/trace.go:171","msg":"trace[251084420] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"163.721468ms","start":"2026-03-03T13:58:33.344503Z","end":"2026-03-03T13:58:33.508224Z","steps":["trace[251084420] 'process raft request' (duration: 79.794032ms)","trace[251084420] 'compare' (duration: 83.740972ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:34.201742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.195103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-03T13:58:34.201767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.954094ms","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-03-03T13:58:34.201847Z","caller":"traceutil/trace.go:171","msg":"trace[620376809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"198.339006ms","start":"2026-03-03T13:58:34.003482Z","end":"2026-03-03T13:58:34.201821Z","steps":["trace[620376809] 'range keys from in-memory index tree' (duration: 197.929197ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:34.201875Z","caller":"traceutil/trace.go:171","msg":"trace[1388262312] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:953; }","duration":"516.072907ms","start":"2026-03-03T13:58:33.685788Z","end":"2026-03-03T13:58:34.20186Z","steps":["trace[1388262312] 'range keys from in-memory index tree' (duration: 515.930734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:34.201876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.373837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T13:58:34.201966Z","caller":"traceutil/trace.go:171","msg":"trace[1424596926] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:953; }","duration":"504.562311ms","start":"2026-03-03T13:58:33.697381Z","end":"2026-03-03T13:58:34.201943Z","steps":["trace[1424596926] 'count revisions from in-memory index tree' (duration: 504.252114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:34.202002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:33.697352Z","time spent":"504.640003ms","remote":"127.0.0.1:59444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-03T13:58:34.201973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.442575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T13:58:34.201983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.125478ms","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-03-03T13:58:34.202084Z","caller":"traceutil/trace.go:171","msg":"trace[1242734210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"428.623359ms","start":"2026-03-03T13:58:33.773437Z","end":"2026-03-03T13:58:34.20206Z","steps":["trace[1242734210] 'range keys from in-memory index tree' (duration: 428.309623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:34.202122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:33.773407Z","time spent":"428.706111ms","remote":"127.0.0.1:58962","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-03-03T13:58:34.202116Z","caller":"traceutil/trace.go:171","msg":"trace[1431929458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"197.338673ms","start":"2026-03-03T13:58:34.004752Z","end":"2026-03-03T13:58:34.202091Z","steps":["trace[1431929458] 'range keys from in-memory index tree' (duration: 196.898242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:34.202155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.915229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-03T13:58:34.202168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.950499ms","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-03-03T13:58:34.202207Z","caller":"traceutil/trace.go:171","msg":"trace[499902083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:953; }","duration":"202.046721ms","start":"2026-03-03T13:58:34.000146Z","end":"2026-03-03T13:58:34.202193Z","steps":["trace[499902083] 'range keys from in-memory index tree' (duration: 201.690153ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:34.202208Z","caller":"traceutil/trace.go:171","msg":"trace[1856533244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"202.082962ms","start":"2026-03-03T13:58:34.000114Z","end":"2026-03-03T13:58:34.202197Z","steps":["trace[1856533244] 'range keys from in-memory index tree' (duration: 201.712724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:34.822655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871139229108791,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T13:58:35.213985Z","caller":"traceutil/trace.go:171","msg":"trace[1339541377] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:997; }","duration":"891.795075ms","start":"2026-03-03T13:58:34.322152Z","end":"2026-03-03T13:58:35.213947Z","steps":["trace[1339541377] 'read index received' (duration: 891.511269ms)","trace[1339541377] 'applied index is now lower than readState.Index' (duration: 282.166µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:35.214055Z","caller":"traceutil/trace.go:171","msg":"trace[1237060397] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.0059779s","start":"2026-03-03T13:58:34.208015Z","end":"2026-03-03T13:58:35.213993Z","steps":["trace[1237060397] 'process raft request' (duration: 1.005779916s)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:35.214097Z","caller":"traceutil/trace.go:171","msg":"trace[216933831] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.006219946s","start":"2026-03-03T13:58:34.207836Z","end":"2026-03-03T13:58:35.214056Z","steps":["trace[216933831] 'process raft request' (duration: 1.005752395s)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:35.21416Z","caller":"traceutil/trace.go:171","msg":"trace[1815581884] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.005190752s","start":"2026-03-03T13:58:34.208954Z","end":"2026-03-03T13:58:35.214144Z","steps":["trace[1815581884] 'process raft request' (duration: 1.004887624s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:35.214301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:34.208004Z","time spent":"1.006164225s","remote":"127.0.0.1:59262","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-03-03T13:58:35.214308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:34.207822Z","time spent":"1.00636579s","remote":"127.0.0.1:59262","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-03-03T13:58:35.214327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:34.208942Z","time spent":"1.005258394s","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T13:58:35.214539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.39326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:35.214576Z","caller":"traceutil/trace.go:171","msg":"trace[939080684] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:958; }","duration":"892.470361ms","start":"2026-03-03T13:58:34.322094Z","end":"2026-03-03T13:58:35.214564Z","steps":["trace[939080684] 'agreement among raft nodes before linearized reading' (duration: 892.38772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:35.214596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.393679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-03T13:58:35.21461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:34.322057Z","time spent":"892.547233ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-03T13:58:35.214634Z","caller":"traceutil/trace.go:171","msg":"trace[916871819] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:958; }","duration":"146.515042ms","start":"2026-03-03T13:58:35.068106Z","end":"2026-03-03T13:58:35.214621Z","steps":["trace[916871819] 'agreement among raft nodes before linearized reading' (duration: 146.45075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:35.214671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.378805ms","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-03-03T13:58:35.214697Z","caller":"traceutil/trace.go:171","msg":"trace[1557910931] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.00555424s","start":"2026-03-03T13:58:34.209133Z","end":"2026-03-03T13:58:35.214687Z","steps":["trace[1557910931] 'process raft request' (duration: 1.004754861s)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:35.214717Z","caller":"traceutil/trace.go:171","msg":"trace[1306626455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:958; }","duration":"552.462147ms","start":"2026-03-03T13:58:34.662241Z","end":"2026-03-03T13:58:35.214703Z","steps":["trace[1306626455] 'agreement among raft nodes before linearized reading' (duration: 552.276822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:35.214751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:34.209123Z","time spent":"1.005595811s","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T13:58:35.214799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.629764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:35.214828Z","caller":"traceutil/trace.go:171","msg":"trace[1753161964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"439.697645ms","start":"2026-03-03T13:58:34.775121Z","end":"2026-03-03T13:58:35.214818Z","steps":["trace[1753161964] 'agreement among raft nodes before linearized reading' (duration: 439.653604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:35.21486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:34.775097Z","time spent":"439.757886ms","remote":"127.0.0.1:58962","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-03-03T13:58:35.214754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:34.662213Z","time spent":"552.532908ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T13:58:35.53265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.535323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T13:58:35.532763Z","caller":"traceutil/trace.go:171","msg":"trace[1190258977] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"255.28512ms","start":"2026-03-03T13:58:35.277468Z","end":"2026-03-03T13:58:35.532753Z","steps":["trace[1190258977] 'process raft request' (duration: 255.251209ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:35.532934Z","caller":"traceutil/trace.go:171","msg":"trace[1320898897] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"280.182634ms","start":"2026-03-03T13:58:35.252746Z","end":"2026-03-03T13:58:35.532928Z","steps":["trace[1320898897] 'process raft request' (duration: 80.321323ms)","trace[1320898897] 'compare' (duration: 199.423621ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:35.799025Z","caller":"traceutil/trace.go:171","msg":"trace[959808463] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"259.546558ms","start":"2026-03-03T13:58:35.539461Z","end":"2026-03-03T13:58:35.799008Z","steps":["trace[959808463] 'process raft request' (duration: 259.436305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:36.045566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.784158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T13:58:36.045711Z","caller":"traceutil/trace.go:171","msg":"trace[485817120] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"188.1457ms","start":"2026-03-03T13:58:35.857545Z","end":"2026-03-03T13:58:36.045691Z","steps":["trace[485817120] 'read index received' (duration: 34.078446ms)","trace[485817120] 'applied index is now lower than readState.Index' (duration: 154.065744ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:36.045737Z","caller":"traceutil/trace.go:171","msg":"trace[2069027285] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"491.688654ms","start":"2026-03-03T13:58:35.554013Z","end":"2026-03-03T13:58:36.045702Z","steps":["trace[2069027285] 'process raft request' (duration: 337.681021ms)","trace[2069027285] 'compare' (duration: 153.658585ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:36.045787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.242483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:36.045826Z","caller":"traceutil/trace.go:171","msg":"trace[978311774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"188.310864ms","start":"2026-03-03T13:58:35.857499Z","end":"2026-03-03T13:58:36.04581Z","steps":["trace[978311774] 'agreement among raft nodes before linearized reading' (duration: 188.248202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:36.0459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:35.554007Z","time spent":"491.802827ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T13:58:36.819921Z","caller":"traceutil/trace.go:171","msg":"trace[1610225535] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"365.684638ms","start":"2026-03-03T13:58:36.454208Z","end":"2026-03-03T13:58:36.819892Z","steps":["trace[1610225535] 'process raft request' (duration: 363.793064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:36.82011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:36.454174Z","time spent":"365.833361ms","remote":"127.0.0.1:59262","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-03-03T13:58:37.703974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.231272ms","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-03-03T13:58:37.704068Z","caller":"traceutil/trace.go:171","msg":"trace[1483242542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"481.356566ms","start":"2026-03-03T13:58:37.222688Z","end":"2026-03-03T13:58:37.704045Z","steps":["trace[1483242542] 'range keys from in-memory index tree' (duration: 481.104089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:37.704094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.565966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:37.704212Z","caller":"traceutil/trace.go:171","msg":"trace[928247858] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:966; }","duration":"548.72483ms","start":"2026-03-03T13:58:37.155468Z","end":"2026-03-03T13:58:37.704193Z","steps":["trace[928247858] 'count revisions from in-memory index tree' (duration: 548.441153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:37.704254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:37.15545Z","time spent":"548.792081ms","remote":"127.0.0.1:33224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-03-03T13:58:37.704131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:37.22268Z","time spent":"481.438837ms","remote":"127.0.0.1:59262","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-03-03T13:58:37.704557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.888775ms","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-03-03T13:58:37.704603Z","caller":"traceutil/trace.go:171","msg":"trace[1671442841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"483.978967ms","start":"2026-03-03T13:58:37.220612Z","end":"2026-03-03T13:58:37.704591Z","steps":["trace[1671442841] 'range keys from in-memory index tree' (duration: 483.640429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:37.704579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.779283ms","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-03-03T13:58:37.704632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:37.22037Z","time spent":"484.254693ms","remote":"127.0.0.1:59262","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-03-03T13:58:37.704682Z","caller":"traceutil/trace.go:171","msg":"trace[1813015220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"483.916356ms","start":"2026-03-03T13:58:37.220736Z","end":"2026-03-03T13:58:37.704653Z","steps":["trace[1813015220] 'range keys from in-memory index tree' (duration: 483.596937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:37.704629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.891253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-03T13:58:37.704744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:37.220728Z","time spent":"483.998067ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-03T13:58:37.704753Z","caller":"traceutil/trace.go:171","msg":"trace[1370174972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"402.044697ms","start":"2026-03-03T13:58:37.302695Z","end":"2026-03-03T13:58:37.704739Z","steps":["trace[1370174972] 'range keys from in-memory index tree' (duration: 401.671458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:37.704787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:37.302676Z","time spent":"402.102507ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T13:58:38.180988Z","caller":"traceutil/trace.go:171","msg":"trace[262870979] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"469.36379ms","start":"2026-03-03T13:58:37.711599Z","end":"2026-03-03T13:58:38.180963Z","steps":["trace[262870979] 'process raft request' (duration: 468.768436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:38.181137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:37.711584Z","time spent":"469.479562ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T13:58:38.190719Z","caller":"traceutil/trace.go:171","msg":"trace[170754791] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"478.713385ms","start":"2026-03-03T13:58:37.71199Z","end":"2026-03-03T13:58:38.190703Z","steps":["trace[170754791] 'process raft request' (duration: 468.634872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:38.191761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:37.711966Z","time spent":"479.429361ms","remote":"127.0.0.1:59262","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-03-03T13:58:38.190851Z","caller":"traceutil/trace.go:171","msg":"trace[549632508] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"477.804623ms","start":"2026-03-03T13:58:37.713029Z","end":"2026-03-03T13:58:38.190834Z","steps":["trace[549632508] 'process raft request' (duration: 477.454645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:38.191054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.625093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:38.192086Z","caller":"traceutil/trace.go:171","msg":"trace[406312050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"378.703669ms","start":"2026-03-03T13:58:37.813371Z","end":"2026-03-03T13:58:38.192075Z","steps":["trace[406312050] 'agreement among raft nodes before linearized reading' (duration: 377.620983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:38.192124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:37.813352Z","time spent":"378.76296ms","remote":"127.0.0.1:58962","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-03-03T13:58:38.192115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:37.713019Z","time spent":"478.968001ms","remote":"127.0.0.1:59262","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-03-03T13:58:38.192276Z","caller":"traceutil/trace.go:171","msg":"trace[1400239258] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"377.263295ms","start":"2026-03-03T13:58:37.813412Z","end":"2026-03-03T13:58:38.190675Z","steps":["trace[1400239258] 'read index received' (duration: 367.450789ms)","trace[1400239258] 'applied index is now lower than readState.Index' (duration: 9.811896ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T13:58:38.242702Z","caller":"traceutil/trace.go:171","msg":"trace[811064383] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"213.990248ms","start":"2026-03-03T13:58:38.028672Z","end":"2026-03-03T13:58:38.242662Z","steps":["trace[811064383] 'process raft request' (duration: 213.800464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:39.145113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.064262ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T13:58:39.166548Z","caller":"traceutil/trace.go:171","msg":"trace[874543034] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"710.637535ms","start":"2026-03-03T13:58:38.455875Z","end":"2026-03-03T13:58:39.166513Z","steps":["trace[874543034] 'read index received' (duration: 38.028127ms)","trace[874543034] 'applied index is now lower than readState.Index' (duration: 672.603218ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T13:58:39.16676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.87564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T13:58:39.166788Z","caller":"traceutil/trace.go:171","msg":"trace[1582430667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"710.954002ms","start":"2026-03-03T13:58:38.455826Z","end":"2026-03-03T13:58:39.16678Z","steps":["trace[1582430667] 'agreement among raft nodes before linearized reading' (duration: 710.785149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:39.166818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:38.455805Z","time spent":"711.005694ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T13:58:39.630823Z","caller":"traceutil/trace.go:171","msg":"trace[1531141452] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"449.267585ms","start":"2026-03-03T13:58:39.181531Z","end":"2026-03-03T13:58:39.630799Z","steps":["trace[1531141452] 'process raft request' (duration: 448.519578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:39.63098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T13:58:39.181506Z","time spent":"449.394058ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T13:58:39.978737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.164488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T13:58:39.978852Z","caller":"traceutil/trace.go:171","msg":"trace[1424581496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"190.351252ms","start":"2026-03-03T13:58:39.788474Z","end":"2026-03-03T13:58:39.978825Z","steps":["trace[1424581496] 'range keys from in-memory index tree' (duration: 190.029105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:39.979284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.559114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T13:58:39.979471Z","caller":"traceutil/trace.go:171","msg":"trace[1770256748] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:972; }","duration":"147.835521ms","start":"2026-03-03T13:58:39.831605Z","end":"2026-03-03T13:58:39.97944Z","steps":["trace[1770256748] 'count revisions from in-memory index tree' (duration: 147.434831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T13:58:39.978744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.552769ms","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-03-03T13:58:39.979671Z","caller":"traceutil/trace.go:171","msg":"trace[850086590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"192.517632ms","start":"2026-03-03T13:58:39.787111Z","end":"2026-03-03T13:58:39.979629Z","steps":["trace[850086590] 'range keys from in-memory index tree' (duration: 191.335355ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:41.801184Z","caller":"traceutil/trace.go:171","msg":"trace[1018848385] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"129.556859ms","start":"2026-03-03T13:58:41.671593Z","end":"2026-03-03T13:58:41.80115Z","steps":["trace[1018848385] 'process raft request' (duration: 128.885913ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:42.259709Z","caller":"traceutil/trace.go:171","msg":"trace[73765813] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"235.911284ms","start":"2026-03-03T13:58:42.02377Z","end":"2026-03-03T13:58:42.259681Z","steps":["trace[73765813] 'process raft request' (duration: 235.675118ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T13:58:45.51041Z","caller":"traceutil/trace.go:171","msg":"trace[2036210657] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"138.832404ms","start":"2026-03-03T13:58:45.371541Z","end":"2026-03-03T13:58:45.510373Z","steps":["trace[2036210657] 'process raft request' (duration: 137.558714ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T14:00:03.004616Z","caller":"traceutil/trace.go:171","msg":"trace[732077864] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"217.651339ms","start":"2026-03-03T14:00:02.78691Z","end":"2026-03-03T14:00:03.004561Z","steps":["trace[732077864] 'process raft request' (duration: 217.225069ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T14:00:41.674677Z","caller":"traceutil/trace.go:171","msg":"trace[1847731161] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"101.621122ms","start":"2026-03-03T14:00:41.573028Z","end":"2026-03-03T14:00:41.674649Z","steps":["trace[1847731161] 'process raft request' (duration: 101.228003ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T14:00:44.961074Z","caller":"traceutil/trace.go:171","msg":"trace[991051544] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"101.818917ms","start":"2026-03-03T14:00:44.859224Z","end":"2026-03-03T14:00:44.961043Z","steps":["trace[991051544] 'process raft request' (duration: 101.630953ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T14:00:47.881257Z","caller":"traceutil/trace.go:171","msg":"trace[928018603] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"122.614393ms","start":"2026-03-03T14:00:47.758613Z","end":"2026-03-03T14:00:47.881227Z","steps":["trace[928018603] 'process raft request' (duration: 122.373817ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T14:00:47.882387Z","caller":"traceutil/trace.go:171","msg":"trace[1799338195] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"122.864829ms","start":"2026-03-03T14:00:47.759507Z","end":"2026-03-03T14:00:47.882372Z","steps":["trace[1799338195] 'process raft request' (duration: 122.795627ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T14:00:51.811601Z","caller":"traceutil/trace.go:171","msg":"trace[927175609] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"110.328545ms","start":"2026-03-03T14:00:51.701234Z","end":"2026-03-03T14:00:51.811562Z","steps":["trace[927175609] 'process raft request' (duration: 110.088319ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T14:02:04.265545Z","caller":"traceutil/trace.go:171","msg":"trace[636006276] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"108.644233ms","start":"2026-03-03T14:02:04.156868Z","end":"2026-03-03T14:02:04.265512Z","steps":["trace[636006276] 'process raft request' (duration: 61.097281ms)","trace[636006276] 'compare' (duration: 47.201034ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T14:02:04.934272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.971212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T14:02:04.934407Z","caller":"traceutil/trace.go:171","msg":"trace[518912608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"162.142226ms","start":"2026-03-03T14:02:04.772235Z","end":"2026-03-03T14:02:04.934378Z","steps":["trace[518912608] 'range keys from in-memory index tree' (duration: 161.855009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T14:02:05.707897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871139229111108,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T14:02:05.827895Z","caller":"traceutil/trace.go:171","msg":"trace[516983385] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1951; }","duration":"621.0258ms","start":"2026-03-03T14:02:05.206844Z","end":"2026-03-03T14:02:05.827869Z","steps":["trace[516983385] 'read index received' (duration: 620.999179ms)","trace[516983385] 'applied index is now lower than readState.Index' (duration: 25.931µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T14:02:05.828415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.541712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-03T14:02:05.82852Z","caller":"traceutil/trace.go:171","msg":"trace[930460608] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1866; }","duration":"621.702836ms","start":"2026-03-03T14:02:05.206792Z","end":"2026-03-03T14:02:05.828495Z","steps":["trace[930460608] 'agreement among raft nodes before linearized reading' (duration: 621.228625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T14:02:05.828697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T14:02:05.206767Z","time spent":"621.911571ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-03T14:02:05.828087Z","caller":"traceutil/trace.go:171","msg":"trace[95523555] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"652.171974ms","start":"2026-03-03T14:02:05.175865Z","end":"2026-03-03T14:02:05.828037Z","steps":["trace[95523555] 'process raft request' (duration: 651.866287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T14:02:05.828991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T14:02:05.175836Z","time spent":"653.055485ms","remote":"127.0.0.1:59262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}