{"level":"warn","ts":"2026-05-09T21:45:16.30684Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:45:16.306914Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T21:45:16.307008Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:45:16.307025Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-09T21:45:16.307046Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T21:45:16.307993Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-09T21:45:16.308073Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T21:45:16.311941Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.679604ms"} {"level":"info","ts":"2026-05-09T21:45:16.322716Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-09T21:45:16.322834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T21:45:16.322867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-09T21:45:16.322892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T21:45:16.322908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-09T21:45:16.323682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-09T21:45:16.332831Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T21:45:16.335079Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T21:45:16.33635Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T21:45:16.338795Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T21:45:16.339195Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T21:45:16.339279Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T21:45:16.339292Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T21:45:16.339742Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T21:45:16.340166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-09T21:45:16.342269Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-09T21:45:16.343911Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T21:45:16.344066Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T21:45:16.344094Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T21:45:16.344198Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-09T21:45:16.344219Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-09T21:45:16.524882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T21:45:16.524936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T21:45:16.524977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-09T21:45:16.524995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-09T21:45:16.525007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-09T21:45:16.525188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-09T21:45:16.525216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-09T21:45:16.526469Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:45:16.526624Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:45:16.526682Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T21:45:16.527077Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T21:45:16.527147Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T21:45:16.527174Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:45:16.528879Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:45:16.528982Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:45:16.529292Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:45:16.53025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T21:45:16.530405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"warn","ts":"2026-05-09T21:45:44.421138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960389341325023,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T21:45:44.498688Z","caller":"traceutil/trace.go:171","msg":"trace[1984137771] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"650.97468ms","start":"2026-05-09T21:45:43.847697Z","end":"2026-05-09T21:45:44.498671Z","steps":["trace[1984137771] 'process raft request' (duration: 650.67383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:44.4989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.677374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T21:45:44.498986Z","caller":"traceutil/trace.go:171","msg":"trace[1955541807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:433; }","duration":"578.805688ms","start":"2026-05-09T21:45:43.920165Z","end":"2026-05-09T21:45:44.498971Z","steps":["trace[1955541807] 'agreement among raft nodes before linearized reading' (duration: 578.632953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:44.499025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:43.920149Z","time spent":"578.870341ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T21:45:44.499183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:43.847675Z","time spent":"651.099264ms","remote":"127.0.0.1:48698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:45:44.499248Z","caller":"traceutil/trace.go:171","msg":"trace[967191585] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"578.415816ms","start":"2026-05-09T21:45:43.920206Z","end":"2026-05-09T21:45:44.498622Z","steps":["trace[967191585] 'read index received' (duration: 577.978991ms)","trace[967191585] 'applied index is now lower than readState.Index' (duration: 435.955µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:45:44.499339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.19666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-09T21:45:44.499484Z","caller":"traceutil/trace.go:171","msg":"trace[1699328930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:433; }","duration":"277.380185ms","start":"2026-05-09T21:45:44.222096Z","end":"2026-05-09T21:45:44.499477Z","steps":["trace[1699328930] 'agreement among raft nodes before linearized reading' (duration: 277.21765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:44.499479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.301852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T21:45:44.499573Z","caller":"traceutil/trace.go:171","msg":"trace[708491040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:433; }","duration":"150.444857ms","start":"2026-05-09T21:45:44.349108Z","end":"2026-05-09T21:45:44.499553Z","steps":["trace[708491040] 'agreement among raft nodes before linearized reading' (duration: 150.264661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:44.499481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.652114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T21:45:44.499744Z","caller":"traceutil/trace.go:171","msg":"trace[446919992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:433; }","duration":"150.882762ms","start":"2026-05-09T21:45:44.348776Z","end":"2026-05-09T21:45:44.499658Z","steps":["trace[446919992] 'agreement among raft nodes before linearized reading' (duration: 150.650534ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:46.75691Z","caller":"traceutil/trace.go:171","msg":"trace[745454392] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"177.527716ms","start":"2026-05-09T21:45:46.579355Z","end":"2026-05-09T21:45:46.756882Z","steps":["trace[745454392] 'process raft request' (duration: 170.628215ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:46.760353Z","caller":"traceutil/trace.go:171","msg":"trace[1051336147] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"167.885283ms","start":"2026-05-09T21:45:46.592439Z","end":"2026-05-09T21:45:46.760324Z","steps":["trace[1051336147] 'process raft request' (duration: 167.614373ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:47.384049Z","caller":"traceutil/trace.go:171","msg":"trace[1798703098] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"566.823917ms","start":"2026-05-09T21:45:46.817193Z","end":"2026-05-09T21:45:47.384017Z","steps":["trace[1798703098] 'process raft request' (duration: 566.688543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:47.384543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:46.817169Z","time spent":"566.947211ms","remote":"127.0.0.1:48632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:45:48.176236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.294102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:45:48.176581Z","caller":"traceutil/trace.go:171","msg":"trace[1553138290] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:555; }","duration":"374.690991ms","start":"2026-05-09T21:45:47.801867Z","end":"2026-05-09T21:45:48.176558Z","steps":["trace[1553138290] 'read index received' (duration: 70.552µs)","trace[1553138290] 'applied index is now lower than readState.Index' (duration: 374.619269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:45:48.176703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.835135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:45:48.176627Z","caller":"traceutil/trace.go:171","msg":"trace[1914493939] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.355126334s","start":"2026-05-09T21:45:46.821471Z","end":"2026-05-09T21:45:48.176597Z","steps":["trace[1914493939] 'process raft request' (duration: 700.194631ms)","trace[1914493939] 'compare' (duration: 654.103185ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:48.176752Z","caller":"traceutil/trace.go:171","msg":"trace[478694718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"374.884908ms","start":"2026-05-09T21:45:47.801853Z","end":"2026-05-09T21:45:48.176738Z","steps":["trace[478694718] 'agreement among raft nodes before linearized reading' (duration: 374.782535ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:48.176763Z","caller":"traceutil/trace.go:171","msg":"trace[1393377509] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.354426121s","start":"2026-05-09T21:45:46.822309Z","end":"2026-05-09T21:45:48.176735Z","steps":["trace[1393377509] 'process raft request' (duration: 1.35409214s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:48.1768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:47.801798Z","time spent":"374.991011ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T21:45:48.176961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:46.822289Z","time spent":"1.354536984s","remote":"127.0.0.1:48632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T21:45:48.176973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:46.821461Z","time spent":"1.355334431s","remote":"127.0.0.1:48632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:45:54.321696Z","caller":"traceutil/trace.go:171","msg":"trace[1279097474] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"155.393303ms","start":"2026-05-09T21:45:54.166272Z","end":"2026-05-09T21:45:54.321666Z","steps":["trace[1279097474] 'process raft request' (duration: 155.238098ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:54.727009Z","caller":"traceutil/trace.go:171","msg":"trace[1947553831] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"400.477125ms","start":"2026-05-09T21:45:54.326469Z","end":"2026-05-09T21:45:54.726946Z","steps":["trace[1947553831] 'process raft request' (duration: 400.205706ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:54.727017Z","caller":"traceutil/trace.go:171","msg":"trace[790339506] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"344.25285ms","start":"2026-05-09T21:45:54.382742Z","end":"2026-05-09T21:45:54.726995Z","steps":["trace[790339506] 'read index received' (duration: 344.070064ms)","trace[790339506] 'applied index is now lower than readState.Index' (duration: 181.636µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:45:54.727216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:54.326421Z","time spent":"400.659662ms","remote":"127.0.0.1:48676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:45:54.727592Z","caller":"traceutil/trace.go:171","msg":"trace[1055271634] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"400.701604ms","start":"2026-05-09T21:45:54.326877Z","end":"2026-05-09T21:45:54.727579Z","steps":["trace[1055271634] 'process raft request' (duration: 400.050092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:54.727679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:54.326863Z","time spent":"400.768505ms","remote":"127.0.0.1:48866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:45:54.727852Z","caller":"traceutil/trace.go:171","msg":"trace[263098027] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"400.951121ms","start":"2026-05-09T21:45:54.326884Z","end":"2026-05-09T21:45:54.727835Z","steps":["trace[263098027] 'process raft request' (duration: 399.954238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:54.727923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:54.326871Z","time spent":"401.005994ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:45:54.72793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.971007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T21:45:54.727983Z","caller":"traceutil/trace.go:171","msg":"trace[1924050462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"171.032369ms","start":"2026-05-09T21:45:54.556935Z","end":"2026-05-09T21:45:54.727967Z","steps":["trace[1924050462] 'agreement among raft nodes before linearized reading' (duration: 170.862433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:54.728225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.46349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T21:45:54.728252Z","caller":"traceutil/trace.go:171","msg":"trace[1252009941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"159.516952ms","start":"2026-05-09T21:45:54.568727Z","end":"2026-05-09T21:45:54.728244Z","steps":["trace[1252009941] 'agreement among raft nodes before linearized reading' (duration: 159.426839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:54.728246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.81025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T21:45:54.728281Z","caller":"traceutil/trace.go:171","msg":"trace[874248739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"164.879342ms","start":"2026-05-09T21:45:54.563392Z","end":"2026-05-09T21:45:54.728271Z","steps":["trace[874248739] 'agreement among raft nodes before linearized reading' (duration: 164.786229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:54.729217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.326959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T21:45:54.729272Z","caller":"traceutil/trace.go:171","msg":"trace[232498652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"160.421703ms","start":"2026-05-09T21:45:54.568824Z","end":"2026-05-09T21:45:54.729245Z","steps":["trace[232498652] 'agreement among raft nodes before linearized reading' (duration: 160.314979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:54.72956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.818036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jfns6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-09T21:45:54.729768Z","caller":"traceutil/trace.go:171","msg":"trace[1012641183] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jfns6; range_end:; response_count:1; response_revision:591; }","duration":"346.932389ms","start":"2026-05-09T21:45:54.382691Z","end":"2026-05-09T21:45:54.729624Z","steps":["trace[1012641183] 'agreement among raft nodes before linearized reading' (duration: 346.540236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:54.730054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:54.382672Z","time spent":"347.368864ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-jfns6\" "} {"level":"warn","ts":"2026-05-09T21:45:55.667921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.732749ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T21:45:57.81437Z","caller":"traceutil/trace.go:171","msg":"trace[755158057] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"229.49696ms","start":"2026-05-09T21:45:57.584842Z","end":"2026-05-09T21:45:57.814339Z","steps":["trace[755158057] 'process raft request' (duration: 228.91763ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:02.327883Z","caller":"traceutil/trace.go:171","msg":"trace[1405279328] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"108.738198ms","start":"2026-05-09T21:46:02.218848Z","end":"2026-05-09T21:46:02.327586Z","steps":["trace[1405279328] 'process raft request' (duration: 91.163559ms)","trace[1405279328] 'compare' (duration: 17.455365ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:46:02.595274Z","caller":"traceutil/trace.go:171","msg":"trace[1268489867] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"110.504467ms","start":"2026-05-09T21:46:02.484739Z","end":"2026-05-09T21:46:02.595243Z","steps":["trace[1268489867] 'process raft request' (duration: 110.366632ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:11.202065Z","caller":"traceutil/trace.go:171","msg":"trace[150555079] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"399.842204ms","start":"2026-05-09T21:46:10.802202Z","end":"2026-05-09T21:46:11.202044Z","steps":["trace[150555079] 'read index received' (duration: 399.648397ms)","trace[150555079] 'applied index is now lower than readState.Index' (duration: 193.137µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:46:11.202431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.768427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T21:46:11.202469Z","caller":"traceutil/trace.go:171","msg":"trace[383655342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"285.85502ms","start":"2026-05-09T21:46:10.916605Z","end":"2026-05-09T21:46:11.20246Z","steps":["trace[383655342] 'agreement among raft nodes before linearized reading' (duration: 285.670644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:11.202355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.124923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:46:11.20261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.17259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T21:46:11.202697Z","caller":"traceutil/trace.go:171","msg":"trace[2044063763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"286.313885ms","start":"2026-05-09T21:46:10.91636Z","end":"2026-05-09T21:46:11.202674Z","steps":["trace[2044063763] 'agreement among raft nodes before linearized reading' (duration: 285.952543ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:11.202714Z","caller":"traceutil/trace.go:171","msg":"trace[835841300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"400.435014ms","start":"2026-05-09T21:46:10.802195Z","end":"2026-05-09T21:46:11.20263Z","steps":["trace[835841300] 'agreement among raft nodes before linearized reading' (duration: 400.065521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:11.202867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:10.802138Z","time spent":"400.705383ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T21:46:11.2022Z","caller":"traceutil/trace.go:171","msg":"trace[1480200531] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"721.211205ms","start":"2026-05-09T21:46:10.480934Z","end":"2026-05-09T21:46:11.202145Z","steps":["trace[1480200531] 'process raft request' (duration: 720.986718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:11.203023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.000934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T21:46:11.20305Z","caller":"traceutil/trace.go:171","msg":"trace[466444994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"379.048946ms","start":"2026-05-09T21:46:10.823993Z","end":"2026-05-09T21:46:11.203042Z","steps":["trace[466444994] 'agreement among raft nodes before linearized reading' (duration: 378.25688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:11.20307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:10.823983Z","time spent":"379.082617ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T21:46:11.203314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:10.480904Z","time spent":"722.115076ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:46:11.2069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.751823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T21:46:11.206983Z","caller":"traceutil/trace.go:171","msg":"trace[117229077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"349.872508ms","start":"2026-05-09T21:46:10.85709Z","end":"2026-05-09T21:46:11.206962Z","steps":["trace[117229077] 'agreement among raft nodes before linearized reading' (duration: 349.618939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:11.207031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:10.857072Z","time spent":"349.94655ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T21:46:13.349338Z","caller":"traceutil/trace.go:171","msg":"trace[436961891] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"132.519645ms","start":"2026-05-09T21:46:13.216795Z","end":"2026-05-09T21:46:13.349315Z","steps":["trace[436961891] 'process raft request' (duration: 132.396231ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:13.349509Z","caller":"traceutil/trace.go:171","msg":"trace[1458386415] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:786; }","duration":"131.982898ms","start":"2026-05-09T21:46:13.217515Z","end":"2026-05-09T21:46:13.349498Z","steps":["trace[1458386415] 'read index received' (duration: 131.598105ms)","trace[1458386415] 'applied index is now lower than readState.Index' (duration: 383.693µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:46:13.349711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.179454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-09T21:46:13.349812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.553549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T21:46:13.349873Z","caller":"traceutil/trace.go:171","msg":"trace[851026771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"132.387451ms","start":"2026-05-09T21:46:13.217474Z","end":"2026-05-09T21:46:13.349861Z","steps":["trace[851026771] 'agreement among raft nodes before linearized reading' (duration: 132.095841ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:13.349895Z","caller":"traceutil/trace.go:171","msg":"trace[673047800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"130.665083ms","start":"2026-05-09T21:46:13.219212Z","end":"2026-05-09T21:46:13.349877Z","steps":["trace[673047800] 'agreement among raft nodes before linearized reading' (duration: 130.430135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:13.350385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.719394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T21:46:13.350525Z","caller":"traceutil/trace.go:171","msg":"trace[1641054295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"131.497091ms","start":"2026-05-09T21:46:13.219017Z","end":"2026-05-09T21:46:13.350514Z","steps":["trace[1641054295] 'agreement among raft nodes before linearized reading' (duration: 130.610251ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:19.00725Z","caller":"traceutil/trace.go:171","msg":"trace[360783837] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"188.622858ms","start":"2026-05-09T21:46:18.818599Z","end":"2026-05-09T21:46:19.007221Z","steps":["trace[360783837] 'process raft request' (duration: 188.493443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:19.224703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.430419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:46:19.225009Z","caller":"traceutil/trace.go:171","msg":"trace[746990490] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"120.478712ms","start":"2026-05-09T21:46:19.10451Z","end":"2026-05-09T21:46:19.224989Z","steps":["trace[746990490] 'compare' (duration: 114.309275ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:19.538856Z","caller":"traceutil/trace.go:171","msg":"trace[177240386] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"117.315076ms","start":"2026-05-09T21:46:19.421512Z","end":"2026-05-09T21:46:19.538827Z","steps":["trace[177240386] 'process raft request' (duration: 77.237381ms)","trace[177240386] 'compare' (duration: 39.94436ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:46:19.594451Z","caller":"traceutil/trace.go:171","msg":"trace[734594766] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"172.757926ms","start":"2026-05-09T21:46:19.421644Z","end":"2026-05-09T21:46:19.594402Z","steps":["trace[734594766] 'process raft request' (duration: 172.596611ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:19.594589Z","caller":"traceutil/trace.go:171","msg":"trace[1241897491] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:858; }","duration":"105.266792ms","start":"2026-05-09T21:46:19.489299Z","end":"2026-05-09T21:46:19.594566Z","steps":["trace[1241897491] 'read index received' (duration: 9.459857ms)","trace[1241897491] 'applied index is now lower than readState.Index' (duration: 95.805944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:46:19.594783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.462968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T21:46:19.594747Z","caller":"traceutil/trace.go:171","msg":"trace[595563911] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"172.207148ms","start":"2026-05-09T21:46:19.422509Z","end":"2026-05-09T21:46:19.594717Z","steps":["trace[595563911] 'process raft request' (duration: 171.842715ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:19.594817Z","caller":"traceutil/trace.go:171","msg":"trace[1907442757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"105.51308ms","start":"2026-05-09T21:46:19.489294Z","end":"2026-05-09T21:46:19.594807Z","steps":["trace[1907442757] 'agreement among raft nodes before linearized reading' (duration: 105.353544ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:19.68717Z","caller":"traceutil/trace.go:171","msg":"trace[1075342500] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"120.581526ms","start":"2026-05-09T21:46:19.566564Z","end":"2026-05-09T21:46:19.687146Z","steps":["trace[1075342500] 'process raft request' (duration: 113.652553ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:33.70114Z","caller":"traceutil/trace.go:171","msg":"trace[1855253830] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"102.186442ms","start":"2026-05-09T21:46:33.59882Z","end":"2026-05-09T21:46:33.701007Z","steps":["trace[1855253830] 'process raft request' (duration: 53.666197ms)","trace[1855253830] 'compare' (duration: 48.236936ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:46:33.966842Z","caller":"traceutil/trace.go:171","msg":"trace[481155868] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"261.820502ms","start":"2026-05-09T21:46:33.70499Z","end":"2026-05-09T21:46:33.96681Z","steps":["trace[481155868] 'process raft request' (duration: 192.993952ms)","trace[481155868] 'compare' (duration: 68.614973ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:46:33.967053Z","caller":"traceutil/trace.go:171","msg":"trace[1921780666] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"262.035678ms","start":"2026-05-09T21:46:33.704992Z","end":"2026-05-09T21:46:33.967028Z","steps":["trace[1921780666] 'process raft request' (duration: 261.760729ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:33.97838Z","caller":"traceutil/trace.go:171","msg":"trace[212993312] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:951; }","duration":"233.752598ms","start":"2026-05-09T21:46:33.744584Z","end":"2026-05-09T21:46:33.978336Z","steps":["trace[212993312] 'read index received' (duration: 153.427073ms)","trace[212993312] 'applied index is now lower than readState.Index' (duration: 80.324585ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:46:33.979488Z","caller":"traceutil/trace.go:171","msg":"trace[1254367136] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"245.585674ms","start":"2026-05-09T21:46:33.733849Z","end":"2026-05-09T21:46:33.979434Z","steps":["trace[1254367136] 'process raft request' (duration: 244.370665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:33.979635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.021588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T21:46:33.979706Z","caller":"traceutil/trace.go:171","msg":"trace[1780438391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"235.137562ms","start":"2026-05-09T21:46:33.744551Z","end":"2026-05-09T21:46:33.979688Z","steps":["trace[1780438391] 'agreement among raft nodes before linearized reading' (duration: 234.253534ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:39.892928Z","caller":"traceutil/trace.go:171","msg":"trace[23411705] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"141.738499ms","start":"2026-05-09T21:46:39.751162Z","end":"2026-05-09T21:46:39.8929Z","steps":["trace[23411705] 'process raft request' (duration: 140.989455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:45.969894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.203905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:46:45.969991Z","caller":"traceutil/trace.go:171","msg":"trace[1712649272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"169.36212ms","start":"2026-05-09T21:46:45.800612Z","end":"2026-05-09T21:46:45.969974Z","steps":["trace[1712649272] 'range keys from in-memory index tree' (duration: 169.07559ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:46.363857Z","caller":"traceutil/trace.go:171","msg":"trace[1896161217] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"216.056365ms","start":"2026-05-09T21:46:46.147773Z","end":"2026-05-09T21:46:46.363829Z","steps":["trace[1896161217] 'process raft request' (duration: 190.401889ms)","trace[1896161217] 'compare' (duration: 25.543013ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:46:47.782536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192955629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T21:46:47.782617Z","caller":"traceutil/trace.go:171","msg":"trace[415546461] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:963; }","duration":"1.193054903s","start":"2026-05-09T21:46:46.589544Z","end":"2026-05-09T21:46:47.782599Z","steps":["trace[415546461] 'count revisions from in-memory index tree' (duration: 1.192886787s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:47.782669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:46.589492Z","time spent":"1.193158676s","remote":"127.0.0.1:49122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:46:47.782753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.097782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T21:46:47.782898Z","caller":"traceutil/trace.go:171","msg":"trace[2110167561] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:963; }","duration":"450.291418ms","start":"2026-05-09T21:46:47.33259Z","end":"2026-05-09T21:46:47.782881Z","steps":["trace[2110167561] 'count revisions from in-memory index tree' (duration: 450.044101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:47.782925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.783358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:46:47.782941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:47.332574Z","time spent":"450.353881ms","remote":"127.0.0.1:48906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-09T21:46:47.782983Z","caller":"traceutil/trace.go:171","msg":"trace[93517290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"980.915712ms","start":"2026-05-09T21:46:46.802051Z","end":"2026-05-09T21:46:47.782966Z","steps":["trace[93517290] 'range keys from in-memory index tree' (duration: 980.684215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:47.78298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.032489ms","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":"warn","ts":"2026-05-09T21:46:47.783014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:46.802029Z","time spent":"980.978734ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T21:46:47.78302Z","caller":"traceutil/trace.go:171","msg":"trace[1107446307] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:963; }","duration":"779.105701ms","start":"2026-05-09T21:46:47.003903Z","end":"2026-05-09T21:46:47.783009Z","steps":["trace[1107446307] 'count revisions from in-memory index tree' (duration: 778.971428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:47.783064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:47.003888Z","time spent":"779.165854ms","remote":"127.0.0.1:49036","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-05-09T21:46:47.783168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029996744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T21:46:47.783192Z","caller":"traceutil/trace.go:171","msg":"trace[372596283] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:963; }","duration":"1.030020635s","start":"2026-05-09T21:46:46.753162Z","end":"2026-05-09T21:46:47.783183Z","steps":["trace[372596283] 'count revisions from in-memory index tree' (duration: 1.029934132s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:47.78321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:46.753116Z","time spent":"1.030089617s","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:46:48.303996Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960389341326110,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T21:46:48.442311Z","caller":"traceutil/trace.go:171","msg":"trace[251652727] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"655.207599ms","start":"2026-05-09T21:46:47.787078Z","end":"2026-05-09T21:46:48.442286Z","steps":["trace[251652727] 'process raft request' (duration: 655.040263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:48.442472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:47.787063Z","time spent":"655.320333ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:46:48.634232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.77462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:46:48.63434Z","caller":"traceutil/trace.go:171","msg":"trace[96416409] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"831.368664ms","start":"2026-05-09T21:46:47.802951Z","end":"2026-05-09T21:46:48.63432Z","steps":["trace[96416409] 'read index received' (duration: 639.21843ms)","trace[96416409] 'applied index is now lower than readState.Index' (duration: 192.148624ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:46:48.63439Z","caller":"traceutil/trace.go:171","msg":"trace[2086882312] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"568.438818ms","start":"2026-05-09T21:46:48.065936Z","end":"2026-05-09T21:46:48.634375Z","steps":["trace[2086882312] 'process raft request' (duration: 421.456311ms)","trace[2086882312] 'compare' (duration: 146.663327ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:46:48.634449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:48.065913Z","time spent":"568.50673ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:46:48.634465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.505909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:46:48.634515Z","caller":"traceutil/trace.go:171","msg":"trace[823340651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"831.608872ms","start":"2026-05-09T21:46:47.80289Z","end":"2026-05-09T21:46:48.634499Z","steps":["trace[823340651] 'agreement among raft nodes before linearized reading' (duration: 831.509469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:48.634558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:47.802865Z","time spent":"831.682195ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T21:46:48.634629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.411739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-09T21:46:48.634662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.20215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-09T21:46:48.634679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.755977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T21:46:48.634704Z","caller":"traceutil/trace.go:171","msg":"trace[642843342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"589.802357ms","start":"2026-05-09T21:46:48.044895Z","end":"2026-05-09T21:46:48.634697Z","steps":["trace[642843342] 'agreement among raft nodes before linearized reading' (duration: 589.739545ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:48.634693Z","caller":"traceutil/trace.go:171","msg":"trace[758882666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"267.508823ms","start":"2026-05-09T21:46:48.367167Z","end":"2026-05-09T21:46:48.634676Z","steps":["trace[758882666] 'agreement among raft nodes before linearized reading' (duration: 267.333187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:48.634553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.129331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T21:46:48.634706Z","caller":"traceutil/trace.go:171","msg":"trace[614210310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"502.283843ms","start":"2026-05-09T21:46:48.132409Z","end":"2026-05-09T21:46:48.634693Z","steps":["trace[614210310] 'agreement among raft nodes before linearized reading' (duration: 502.17144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:48.634721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:48.044887Z","time spent":"589.830119ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T21:46:48.634743Z","caller":"traceutil/trace.go:171","msg":"trace[589166726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:965; }","duration":"614.354698ms","start":"2026-05-09T21:46:48.020374Z","end":"2026-05-09T21:46:48.634729Z","steps":["trace[589166726] 'agreement among raft nodes before linearized reading' (duration: 614.053379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:48.634752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:48.132386Z","time spent":"502.349715ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T21:46:48.634789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:48.020349Z","time spent":"614.427051ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T21:46:55.218431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.829301ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T21:46:56.835029Z","caller":"traceutil/trace.go:171","msg":"trace[1276992588] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"140.967796ms","start":"2026-05-09T21:46:56.694042Z","end":"2026-05-09T21:46:56.83501Z","steps":["trace[1276992588] 'read index received' (duration: 140.830941ms)","trace[1276992588] 'applied index is now lower than readState.Index' (duration: 136.095µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:46:56.835098Z","caller":"traceutil/trace.go:171","msg":"trace[1654026476] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"145.529451ms","start":"2026-05-09T21:46:56.689532Z","end":"2026-05-09T21:46:56.835061Z","steps":["trace[1654026476] 'process raft request' (duration: 145.358486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:56.83522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.157101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T21:46:56.835253Z","caller":"traceutil/trace.go:171","msg":"trace[1010180548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"141.256695ms","start":"2026-05-09T21:46:56.693987Z","end":"2026-05-09T21:46:56.835244Z","steps":["trace[1010180548] 'agreement among raft nodes before linearized reading' (duration: 141.127631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:56.835313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.926492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T21:46:56.835368Z","caller":"traceutil/trace.go:171","msg":"trace[1782536840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"140.025826ms","start":"2026-05-09T21:46:56.695329Z","end":"2026-05-09T21:46:56.835355Z","steps":["trace[1782536840] 'agreement among raft nodes before linearized reading' (duration: 139.84164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:56.835405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.418565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-09T21:46:56.835422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.379985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T21:46:56.835478Z","caller":"traceutil/trace.go:171","msg":"trace[576407793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"116.539769ms","start":"2026-05-09T21:46:56.718921Z","end":"2026-05-09T21:46:56.83546Z","steps":["trace[576407793] 'agreement among raft nodes before linearized reading' (duration: 116.307732ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:56.835501Z","caller":"traceutil/trace.go:171","msg":"trace[1777355333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"139.482769ms","start":"2026-05-09T21:46:56.696Z","end":"2026-05-09T21:46:56.835482Z","steps":["trace[1777355333] 'agreement among raft nodes before linearized reading' (duration: 139.251841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:57.074455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.040655ms","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-05-09T21:46:57.074548Z","caller":"traceutil/trace.go:171","msg":"trace[209677682] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:996; }","duration":"145.19038ms","start":"2026-05-09T21:46:56.929335Z","end":"2026-05-09T21:46:57.074525Z","steps":["trace[209677682] 'count revisions from in-memory index tree' (duration: 144.933632ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:10.833932Z","caller":"traceutil/trace.go:171","msg":"trace[1084034181] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"244.48726ms","start":"2026-05-09T21:47:10.589425Z","end":"2026-05-09T21:47:10.833912Z","steps":["trace[1084034181] 'process raft request' (duration: 244.369626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:11.445293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960389341326295,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T21:47:11.620419Z","caller":"traceutil/trace.go:171","msg":"trace[983703108] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"675.402471ms","start":"2026-05-09T21:47:10.944993Z","end":"2026-05-09T21:47:11.620396Z","steps":["trace[983703108] 'read index received' (duration: 675.178284ms)","trace[983703108] 'applied index is now lower than readState.Index' (duration: 223.217µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:11.620476Z","caller":"traceutil/trace.go:171","msg":"trace[1521757650] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"700.813521ms","start":"2026-05-09T21:47:10.919635Z","end":"2026-05-09T21:47:11.620448Z","steps":["trace[1521757650] 'process raft request' (duration: 700.611715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:11.620633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:10.919601Z","time spent":"700.948985ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:47:11.620646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.638159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-09T21:47:11.620666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.894835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T21:47:11.620702Z","caller":"traceutil/trace.go:171","msg":"trace[1469958883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"675.757544ms","start":"2026-05-09T21:47:10.944929Z","end":"2026-05-09T21:47:11.620687Z","steps":["trace[1469958883] 'agreement among raft nodes before linearized reading' (duration: 675.582088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:11.6207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.136696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T21:47:11.620713Z","caller":"traceutil/trace.go:171","msg":"trace[1130750696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1039; }","duration":"652.974118ms","start":"2026-05-09T21:47:10.967727Z","end":"2026-05-09T21:47:11.620701Z","steps":["trace[1130750696] 'agreement among raft nodes before linearized reading' (duration: 652.847464ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:11.620745Z","caller":"traceutil/trace.go:171","msg":"trace[1573640207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"627.221729ms","start":"2026-05-09T21:47:10.99351Z","end":"2026-05-09T21:47:11.620732Z","steps":["trace[1573640207] 'agreement among raft nodes before linearized reading' (duration: 627.096665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:11.620746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:10.967713Z","time spent":"653.026179ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:11.620753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:10.94491Z","time spent":"675.825205ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:11.620776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:10.993498Z","time spent":"627.27064ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T21:47:11.620871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.353878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T21:47:11.620945Z","caller":"traceutil/trace.go:171","msg":"trace[1851565389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"646.503272ms","start":"2026-05-09T21:47:10.974425Z","end":"2026-05-09T21:47:11.620928Z","steps":["trace[1851565389] 'agreement among raft nodes before linearized reading' (duration: 646.206643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:11.621012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:10.974408Z","time spent":"646.573575ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:12.472038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.296818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:47:12.472274Z","caller":"traceutil/trace.go:171","msg":"trace[1065217818] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1082; }","duration":"669.218394ms","start":"2026-05-09T21:47:11.803042Z","end":"2026-05-09T21:47:12.47226Z","steps":["trace[1065217818] 'read index received' (duration: 235.550994ms)","trace[1065217818] 'applied index is now lower than readState.Index' (duration: 433.66676ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:12.472347Z","caller":"traceutil/trace.go:171","msg":"trace[85256782] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"846.694292ms","start":"2026-05-09T21:47:11.625645Z","end":"2026-05-09T21:47:12.472339Z","steps":["trace[85256782] 'process raft request' (duration: 413.024342ms)","trace[85256782] 'compare' (duration: 433.012139ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:12.472395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:11.625623Z","time spent":"846.746073ms","remote":"127.0.0.1:48896","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-05-09T21:47:12.472619Z","caller":"traceutil/trace.go:171","msg":"trace[720471040] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"846.616559ms","start":"2026-05-09T21:47:11.625995Z","end":"2026-05-09T21:47:12.472612Z","steps":["trace[720471040] 'process raft request' (duration: 846.138184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:12.472662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:11.625942Z","time spent":"846.699911ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:47:12.472816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.588447ms","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":"warn","ts":"2026-05-09T21:47:12.472976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.941397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:12.472958Z","caller":"traceutil/trace.go:171","msg":"trace[1564754929] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"845.776302ms","start":"2026-05-09T21:47:11.627156Z","end":"2026-05-09T21:47:12.472932Z","steps":["trace[1564754929] 'process raft request' (duration: 845.08172ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:12.473006Z","caller":"traceutil/trace.go:171","msg":"trace[1141658025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"670.005619ms","start":"2026-05-09T21:47:11.802993Z","end":"2026-05-09T21:47:12.472999Z","steps":["trace[1141658025] 'agreement among raft nodes before linearized reading' (duration: 669.961947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:12.473028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:11.802974Z","time spent":"670.04929ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T21:47:12.473108Z","caller":"traceutil/trace.go:171","msg":"trace[1938263707] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"846.105852ms","start":"2026-05-09T21:47:11.626981Z","end":"2026-05-09T21:47:12.473086Z","steps":["trace[1938263707] 'process raft request' (duration: 845.231625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:12.473116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:11.627139Z","time spent":"845.878225ms","remote":"127.0.0.1:48896","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-05-09T21:47:12.473211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:11.626964Z","time spent":"846.196746ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:12.47387Z","caller":"traceutil/trace.go:171","msg":"trace[917415489] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1043; }","duration":"389.723621ms","start":"2026-05-09T21:47:12.083156Z","end":"2026-05-09T21:47:12.47288Z","steps":["trace[917415489] 'agreement among raft nodes before linearized reading' (duration: 389.594567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:12.47392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:12.083133Z","time spent":"390.773835ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:47:14.307055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960389341326308,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:47:14.790272Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.09457471s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T21:47:14.790557Z","caller":"traceutil/trace.go:171","msg":"trace[473051089] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"1.164746893s","start":"2026-05-09T21:47:13.625791Z","end":"2026-05-09T21:47:14.790538Z","steps":["trace[473051089] 'process raft request' (duration: 1.164624049s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:14.790664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:13.625771Z","time spent":"1.164820776s","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:14.956147Z","caller":"traceutil/trace.go:171","msg":"trace[301342321] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1087; }","duration":"1.153600439s","start":"2026-05-09T21:47:13.802524Z","end":"2026-05-09T21:47:14.956125Z","steps":["trace[301342321] 'read index received' (duration: 987.997399ms)","trace[301342321] 'applied index is now lower than readState.Index' (duration: 165.60195ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:14.956251Z","caller":"traceutil/trace.go:171","msg":"trace[1467991353] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"899.323437ms","start":"2026-05-09T21:47:14.056902Z","end":"2026-05-09T21:47:14.956226Z","steps":["trace[1467991353] 'process raft request' (duration: 880.035153ms)","trace[1467991353] 'compare' (duration: 19.095868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:14.956314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153774094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:47:14.95634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050361463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T21:47:14.956361Z","caller":"traceutil/trace.go:171","msg":"trace[1258118630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"1.153869717s","start":"2026-05-09T21:47:13.80248Z","end":"2026-05-09T21:47:14.95635Z","steps":["trace[1258118630] 'agreement among raft nodes before linearized reading' (duration: 1.153784645s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:14.95638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:14.056882Z","time spent":"899.43309ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:47:14.956396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:13.802457Z","time spent":"1.15393184s","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T21:47:14.956392Z","caller":"traceutil/trace.go:171","msg":"trace[1961485514] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1046; }","duration":"1.050535829s","start":"2026-05-09T21:47:13.905844Z","end":"2026-05-09T21:47:14.95638Z","steps":["trace[1961485514] 'agreement among raft nodes before linearized reading' (duration: 1.050448036s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:14.956516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:13.905818Z","time spent":"1.050687683s","remote":"127.0.0.1:48792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:47:14.956656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.442455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T21:47:14.956692Z","caller":"traceutil/trace.go:171","msg":"trace[582084159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1046; }","duration":"478.511657ms","start":"2026-05-09T21:47:14.478171Z","end":"2026-05-09T21:47:14.956683Z","steps":["trace[582084159] 'agreement among raft nodes before linearized reading' (duration: 478.420164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:14.956724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:14.478152Z","time spent":"478.565178ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T21:47:14.956711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.507501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:14.956825Z","caller":"traceutil/trace.go:171","msg":"trace[184158393] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1046; }","duration":"991.691157ms","start":"2026-05-09T21:47:13.965109Z","end":"2026-05-09T21:47:14.9568Z","steps":["trace[184158393] 'agreement among raft nodes before linearized reading' (duration: 991.49053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:14.956878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.651611ms","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-05-09T21:47:14.956888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.654541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T21:47:14.956915Z","caller":"traceutil/trace.go:171","msg":"trace[1819451555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"478.718083ms","start":"2026-05-09T21:47:14.478187Z","end":"2026-05-09T21:47:14.956905Z","steps":["trace[1819451555] 'agreement among raft nodes before linearized reading' (duration: 478.607339ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:14.956923Z","caller":"traceutil/trace.go:171","msg":"trace[1587836845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"478.703753ms","start":"2026-05-09T21:47:14.478209Z","end":"2026-05-09T21:47:14.956912Z","steps":["trace[1587836845] 'agreement among raft nodes before linearized reading' (duration: 478.572789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:14.956949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:14.478169Z","time spent":"478.772465ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:14.956955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:14.4782Z","time spent":"478.745414ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:14.956961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.679402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T21:47:14.957055Z","caller":"traceutil/trace.go:171","msg":"trace[523774067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1046; }","duration":"478.803716ms","start":"2026-05-09T21:47:14.478221Z","end":"2026-05-09T21:47:14.957025Z","steps":["trace[523774067] 'agreement among raft nodes before linearized reading' (duration: 478.580359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:14.957092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:14.478193Z","time spent":"478.891059ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:14.956899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:13.965096Z","time spent":"991.79432ms","remote":"127.0.0.1:49200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:47:14.956761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.008246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:14.957244Z","caller":"traceutil/trace.go:171","msg":"trace[1736440173] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1046; }","duration":"838.525242ms","start":"2026-05-09T21:47:14.118708Z","end":"2026-05-09T21:47:14.957233Z","steps":["trace[1736440173] 'agreement among raft nodes before linearized reading' (duration: 838.025677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:14.957288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:14.118696Z","time spent":"838.580094ms","remote":"127.0.0.1:48948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-05-09T21:47:31.617096Z","caller":"traceutil/trace.go:171","msg":"trace[212723082] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"486.526281ms","start":"2026-05-09T21:47:31.130552Z","end":"2026-05-09T21:47:31.617079Z","steps":["trace[212723082] 'read index received' (duration: 486.365596ms)","trace[212723082] 'applied index is now lower than readState.Index' (duration: 159.805µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:31.617181Z","caller":"traceutil/trace.go:171","msg":"trace[421469227] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"736.170745ms","start":"2026-05-09T21:47:30.880996Z","end":"2026-05-09T21:47:31.617167Z","steps":["trace[421469227] 'process raft request' (duration: 735.970369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.617288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:30.880974Z","time spent":"736.241717ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:47:31.61729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.526087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-09T21:47:31.617294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.724467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-09T21:47:31.617304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.585819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T21:47:31.617339Z","caller":"traceutil/trace.go:171","msg":"trace[139426081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1150; }","duration":"478.609099ms","start":"2026-05-09T21:47:31.138718Z","end":"2026-05-09T21:47:31.617327Z","steps":["trace[139426081] 'agreement among raft nodes before linearized reading' (duration: 478.472805ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:31.617351Z","caller":"traceutil/trace.go:171","msg":"trace[1715196269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1150; }","duration":"486.81953ms","start":"2026-05-09T21:47:31.130518Z","end":"2026-05-09T21:47:31.617337Z","steps":["trace[1715196269] 'agreement among raft nodes before linearized reading' (duration: 486.646995ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:31.61736Z","caller":"traceutil/trace.go:171","msg":"trace[790102603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1150; }","duration":"478.689702ms","start":"2026-05-09T21:47:31.138655Z","end":"2026-05-09T21:47:31.617345Z","steps":["trace[790102603] 'agreement among raft nodes before linearized reading' (duration: 478.527276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.617366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:31.138702Z","time spent":"478.659771ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:31.617389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:31.138633Z","time spent":"478.750434ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:31.617383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:31.130503Z","time spent":"486.873613ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T21:47:31.617576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.01107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T21:47:31.617669Z","caller":"traceutil/trace.go:171","msg":"trace[292047155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1150; }","duration":"478.134454ms","start":"2026-05-09T21:47:31.13951Z","end":"2026-05-09T21:47:31.617644Z","steps":["trace[292047155] 'agreement among raft nodes before linearized reading' (duration: 477.831144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.617521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.668142ms","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-05-09T21:47:31.617716Z","caller":"traceutil/trace.go:171","msg":"trace[1790479474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1150; }","duration":"478.916289ms","start":"2026-05-09T21:47:31.138791Z","end":"2026-05-09T21:47:31.617707Z","steps":["trace[1790479474] 'agreement among raft nodes before linearized reading' (duration: 478.646801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.617733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:31.13876Z","time spent":"478.968031ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:31.617743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:31.139501Z","time spent":"478.227847ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T21:47:31.895101Z","caller":"traceutil/trace.go:171","msg":"trace[1866484601] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"274.032023ms","start":"2026-05-09T21:47:31.621037Z","end":"2026-05-09T21:47:31.895069Z","steps":["trace[1866484601] 'process raft request' (duration: 267.881642ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:31.913744Z","caller":"traceutil/trace.go:171","msg":"trace[330003475] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"291.651518ms","start":"2026-05-09T21:47:31.622074Z","end":"2026-05-09T21:47:31.913726Z","steps":["trace[330003475] 'process raft request' (duration: 291.610837ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:31.913772Z","caller":"traceutil/trace.go:171","msg":"trace[511190543] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"291.914527ms","start":"2026-05-09T21:47:31.621828Z","end":"2026-05-09T21:47:31.913742Z","steps":["trace[511190543] 'process raft request' (duration: 291.754832ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:31.913811Z","caller":"traceutil/trace.go:171","msg":"trace[729688726] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"291.71052ms","start":"2026-05-09T21:47:31.622072Z","end":"2026-05-09T21:47:31.913783Z","steps":["trace[729688726] 'process raft request' (duration: 291.590856ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:31.913913Z","caller":"traceutil/trace.go:171","msg":"trace[613756396] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1199; }","duration":"108.82195ms","start":"2026-05-09T21:47:31.80508Z","end":"2026-05-09T21:47:31.913902Z","steps":["trace[613756396] 'read index received' (duration: 83.853025ms)","trace[613756396] 'applied index is now lower than readState.Index' (duration: 24.967785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:31.913979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.898723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:31.913976Z","caller":"traceutil/trace.go:171","msg":"trace[1843483539] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"292.057791ms","start":"2026-05-09T21:47:31.621907Z","end":"2026-05-09T21:47:31.913964Z","steps":["trace[1843483539] 'process raft request' (duration: 291.799233ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:31.914032Z","caller":"traceutil/trace.go:171","msg":"trace[789066738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"108.991476ms","start":"2026-05-09T21:47:31.805026Z","end":"2026-05-09T21:47:31.914018Z","steps":["trace[789066738] 'agreement among raft nodes before linearized reading' (duration: 108.913703ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:45.802738Z","caller":"traceutil/trace.go:171","msg":"trace[1927918661] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"107.054282ms","start":"2026-05-09T21:47:45.695664Z","end":"2026-05-09T21:47:45.802719Z","steps":["trace[1927918661] 'process raft request' (duration: 106.929448ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:45.846144Z","caller":"traceutil/trace.go:171","msg":"trace[934492231] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"149.57697ms","start":"2026-05-09T21:47:45.696487Z","end":"2026-05-09T21:47:45.846064Z","steps":["trace[934492231] 'process raft request' (duration: 117.461562ms)","trace[934492231] 'compare' (duration: 32.027615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:46.506132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.757073ms","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-05-09T21:47:46.506176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.389954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T21:47:46.506232Z","caller":"traceutil/trace.go:171","msg":"trace[248942730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1204; }","duration":"457.95018ms","start":"2026-05-09T21:47:46.048262Z","end":"2026-05-09T21:47:46.506212Z","steps":["trace[248942730] 'range keys from in-memory index tree' (duration: 457.609549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:46.506233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.25679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T21:47:46.506274Z","caller":"traceutil/trace.go:171","msg":"trace[721609881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"458.549859ms","start":"2026-05-09T21:47:46.047704Z","end":"2026-05-09T21:47:46.506254Z","steps":["trace[721609881] 'range keys from in-memory index tree' (duration: 458.171747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:46.506278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.048253Z","time spent":"458.012952ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T21:47:46.506311Z","caller":"traceutil/trace.go:171","msg":"trace[1537953690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"458.362233ms","start":"2026-05-09T21:47:46.047929Z","end":"2026-05-09T21:47:46.506292Z","steps":["trace[1537953690] 'range keys from in-memory index tree' (duration: 458.052253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:46.506324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.047684Z","time spent":"458.629302ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:46.506342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.819575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-09T21:47:46.506365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.047923Z","time spent":"458.433326ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T21:47:46.506404Z","caller":"traceutil/trace.go:171","msg":"trace[1044616230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"457.898189ms","start":"2026-05-09T21:47:46.048492Z","end":"2026-05-09T21:47:46.50639Z","steps":["trace[1044616230] 'range keys from in-memory index tree' (duration: 457.668571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:46.506435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.048468Z","time spent":"457.95986ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T21:47:46.507025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.62163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T21:47:46.50705Z","caller":"traceutil/trace.go:171","msg":"trace[867157784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1204; }","duration":"461.670521ms","start":"2026-05-09T21:47:46.045372Z","end":"2026-05-09T21:47:46.507042Z","steps":["trace[867157784] 'range keys from in-memory index tree' (duration: 460.412221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:46.507069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.045356Z","time spent":"461.707923ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T21:47:46.942396Z","caller":"traceutil/trace.go:171","msg":"trace[2146400479] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"431.432145ms","start":"2026-05-09T21:47:46.510945Z","end":"2026-05-09T21:47:46.942377Z","steps":["trace[2146400479] 'process raft request' (duration: 431.29078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:46.942527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.510927Z","time spent":"431.528137ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:46.974416Z","caller":"traceutil/trace.go:171","msg":"trace[266357700] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"463.339955ms","start":"2026-05-09T21:47:46.511052Z","end":"2026-05-09T21:47:46.974392Z","steps":["trace[266357700] 'process raft request' (duration: 463.18714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:46.974579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.511029Z","time spent":"463.457779ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:46.974804Z","caller":"traceutil/trace.go:171","msg":"trace[1335666283] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"463.556793ms","start":"2026-05-09T21:47:46.511229Z","end":"2026-05-09T21:47:46.974786Z","steps":["trace[1335666283] 'process raft request' (duration: 463.125089ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:46.974857Z","caller":"traceutil/trace.go:171","msg":"trace[1663614608] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1256; }","duration":"172.516059ms","start":"2026-05-09T21:47:46.802312Z","end":"2026-05-09T21:47:46.974828Z","steps":["trace[1663614608] 'read index received' (duration: 139.946806ms)","trace[1663614608] 'applied index is now lower than readState.Index' (duration: 32.567963ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:46.974938Z","caller":"traceutil/trace.go:171","msg":"trace[1056486847] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"462.86519ms","start":"2026-05-09T21:47:46.51206Z","end":"2026-05-09T21:47:46.974925Z","steps":["trace[1056486847] 'process raft request' (duration: 462.534079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:46.974908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.511221Z","time spent":"463.645616ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:47:46.975019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.512045Z","time spent":"462.932982ms","remote":"127.0.0.1:48896","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-05-09T21:47:46.975033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.693694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:46.975578Z","caller":"traceutil/trace.go:171","msg":"trace[150681385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"172.922012ms","start":"2026-05-09T21:47:46.802278Z","end":"2026-05-09T21:47:46.9752Z","steps":["trace[150681385] 'agreement among raft nodes before linearized reading' (duration: 172.660713ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:46.975557Z","caller":"traceutil/trace.go:171","msg":"trace[1821992759] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"462.25604ms","start":"2026-05-09T21:47:46.512811Z","end":"2026-05-09T21:47:46.975067Z","steps":["trace[1821992759] 'process raft request' (duration: 461.954541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:46.975701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:46.512806Z","time spent":"462.817829ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:47.913131Z","caller":"traceutil/trace.go:171","msg":"trace[411799912] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"105.727148ms","start":"2026-05-09T21:47:47.807375Z","end":"2026-05-09T21:47:47.913102Z","steps":["trace[411799912] 'process raft request' (duration: 105.516171ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:49.153728Z","caller":"traceutil/trace.go:171","msg":"trace[573602236] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"167.013539ms","start":"2026-05-09T21:47:48.986688Z","end":"2026-05-09T21:47:49.153702Z","steps":["trace[573602236] 'process raft request' (duration: 166.968767ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:49.153784Z","caller":"traceutil/trace.go:171","msg":"trace[1995052672] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"167.63932ms","start":"2026-05-09T21:47:48.986119Z","end":"2026-05-09T21:47:49.153758Z","steps":["trace[1995052672] 'process raft request' (duration: 167.370501ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:49.153805Z","caller":"traceutil/trace.go:171","msg":"trace[1157469691] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"167.511435ms","start":"2026-05-09T21:47:48.986266Z","end":"2026-05-09T21:47:49.153777Z","steps":["trace[1157469691] 'process raft request' (duration: 167.35094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:49.737319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.371837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:47:49.737465Z","caller":"traceutil/trace.go:171","msg":"trace[1178184362] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"462.837179ms","start":"2026-05-09T21:47:49.274612Z","end":"2026-05-09T21:47:49.73745Z","steps":["trace[1178184362] 'read index received' (duration: 216.307357ms)","trace[1178184362] 'applied index is now lower than readState.Index' (duration: 246.528562ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:49.737541Z","caller":"traceutil/trace.go:171","msg":"trace[380581933] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"481.275581ms","start":"2026-05-09T21:47:49.256255Z","end":"2026-05-09T21:47:49.73753Z","steps":["trace[380581933] 'process raft request' (duration: 234.637175ms)","trace[380581933] 'compare' (duration: 246.268414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:49.737595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:49.256238Z","time spent":"481.326322ms","remote":"127.0.0.1:48676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T21:47:49.737951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.274693ms","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-05-09T21:47:49.738041Z","caller":"traceutil/trace.go:171","msg":"trace[1958628014] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1218; }","duration":"463.447169ms","start":"2026-05-09T21:47:49.274571Z","end":"2026-05-09T21:47:49.738019Z","steps":["trace[1958628014] 'agreement among raft nodes before linearized reading' (duration: 463.218402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:49.738089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:49.274555Z","time spent":"463.521522ms","remote":"127.0.0.1:48896","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-05-09T21:47:50.007506Z","caller":"traceutil/trace.go:171","msg":"trace[730524218] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"264.116316ms","start":"2026-05-09T21:47:49.74336Z","end":"2026-05-09T21:47:50.007477Z","steps":["trace[730524218] 'process raft request' (duration: 264.059985ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:50.007549Z","caller":"traceutil/trace.go:171","msg":"trace[1669335015] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"265.722759ms","start":"2026-05-09T21:47:49.741794Z","end":"2026-05-09T21:47:50.007517Z","steps":["trace[1669335015] 'process raft request' (duration: 265.45233ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:50.207669Z","caller":"traceutil/trace.go:171","msg":"trace[224309793] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1273; }","duration":"196.204491ms","start":"2026-05-09T21:47:50.011446Z","end":"2026-05-09T21:47:50.207651Z","steps":["trace[224309793] 'read index received' (duration: 180.597261ms)","trace[224309793] 'applied index is now lower than readState.Index' (duration: 15.60631ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:50.207753Z","caller":"traceutil/trace.go:171","msg":"trace[123461994] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"289.723672ms","start":"2026-05-09T21:47:49.918024Z","end":"2026-05-09T21:47:50.207747Z","steps":["trace[123461994] 'process raft request' (duration: 289.586007ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:50.207809Z","caller":"traceutil/trace.go:171","msg":"trace[1270353849] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"390.871561ms","start":"2026-05-09T21:47:49.816924Z","end":"2026-05-09T21:47:50.207796Z","steps":["trace[1270353849] 'process raft request' (duration: 375.107837ms)","trace[1270353849] 'compare' (duration: 15.484065ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:50.207894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:49.816905Z","time spent":"390.931454ms","remote":"127.0.0.1:48614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:47:50.207975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-09T21:47:50.208058Z","caller":"traceutil/trace.go:171","msg":"trace[997222895] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1222; }","duration":"196.639945ms","start":"2026-05-09T21:47:50.0114Z","end":"2026-05-09T21:47:50.20804Z","steps":["trace[997222895] 'agreement among raft nodes before linearized reading' (duration: 196.377717ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:50.229595Z","caller":"traceutil/trace.go:171","msg":"trace[2064231667] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"216.440411ms","start":"2026-05-09T21:47:50.013112Z","end":"2026-05-09T21:47:50.229553Z","steps":["trace[2064231667] 'process raft request' (duration: 216.297226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:50.229621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.948436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T21:47:50.229692Z","caller":"traceutil/trace.go:171","msg":"trace[448441928] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1223; }","duration":"213.04921ms","start":"2026-05-09T21:47:50.016627Z","end":"2026-05-09T21:47:50.229676Z","steps":["trace[448441928] 'agreement among raft nodes before linearized reading' (duration: 212.913456ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:51.368008Z","caller":"traceutil/trace.go:171","msg":"trace[1137575885] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"111.917911ms","start":"2026-05-09T21:47:51.256072Z","end":"2026-05-09T21:47:51.36799Z","steps":["trace[1137575885] 'process raft request' (duration: 70.130398ms)","trace[1137575885] 'compare' (duration: 41.69174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:48:01.505415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099078674s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:48:01.505546Z","caller":"traceutil/trace.go:171","msg":"trace[1117414787] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"1.093275086s","start":"2026-05-09T21:48:00.412248Z","end":"2026-05-09T21:48:01.505523Z","steps":["trace[1117414787] 'read index received' (duration: 83.303µs)","trace[1117414787] 'applied index is now lower than readState.Index' (duration: 1.093190033s)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:01.505632Z","caller":"traceutil/trace.go:171","msg":"trace[557796579] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"1.206917763s","start":"2026-05-09T21:48:00.298699Z","end":"2026-05-09T21:48:01.505616Z","steps":["trace[557796579] 'process raft request' (duration: 107.534368ms)","trace[557796579] 'compare' (duration: 1.098473225s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:48:01.505705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:00.298654Z","time spent":"1.207012906s","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:48:01.505866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093601127s","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:526"} {"level":"info","ts":"2026-05-09T21:48:01.505941Z","caller":"traceutil/trace.go:171","msg":"trace[794612233] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1309; }","duration":"1.093733671s","start":"2026-05-09T21:48:00.412185Z","end":"2026-05-09T21:48:01.505919Z","steps":["trace[794612233] 'agreement among raft nodes before linearized reading' (duration: 1.093470612s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:01.505982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:00.412163Z","time spent":"1.093810323s","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-09T21:48:01.780588Z","caller":"traceutil/trace.go:171","msg":"trace[1648873827] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"274.993422ms","start":"2026-05-09T21:48:01.505578Z","end":"2026-05-09T21:48:01.780571Z","steps":["trace[1648873827] 'read index received' (duration: 274.830566ms)","trace[1648873827] 'applied index is now lower than readState.Index' (duration: 161.926µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:01.780765Z","caller":"traceutil/trace.go:171","msg":"trace[2095824674] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"789.435004ms","start":"2026-05-09T21:48:00.991322Z","end":"2026-05-09T21:48:01.780757Z","steps":["trace[2095824674] 'process raft request' (duration: 789.133274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:01.780861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:00.991301Z","time spent":"789.489535ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:48:01.781116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.930305ms","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-05-09T21:48:01.781145Z","caller":"traceutil/trace.go:171","msg":"trace[654628188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"528.998697ms","start":"2026-05-09T21:48:01.25214Z","end":"2026-05-09T21:48:01.781138Z","steps":["trace[654628188] 'agreement among raft nodes before linearized reading' (duration: 528.887834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:01.781165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:01.252124Z","time spent":"529.036009ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T21:48:01.781279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.430642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T21:48:01.781304Z","caller":"traceutil/trace.go:171","msg":"trace[225734617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"529.490683ms","start":"2026-05-09T21:48:01.251807Z","end":"2026-05-09T21:48:01.781298Z","steps":["trace[225734617] 'agreement among raft nodes before linearized reading' (duration: 529.435932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:01.78132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:01.251795Z","time spent":"529.521255ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T21:48:01.781517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.529725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T21:48:01.78155Z","caller":"traceutil/trace.go:171","msg":"trace[1854420643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"533.627768ms","start":"2026-05-09T21:48:01.247915Z","end":"2026-05-09T21:48:01.781543Z","steps":["trace[1854420643] 'agreement among raft nodes before linearized reading' (duration: 533.546726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:01.781569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:01.247879Z","time spent":"533.68525ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T21:48:01.781722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.881196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T21:48:01.781797Z","caller":"traceutil/trace.go:171","msg":"trace[662849852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"648.00342ms","start":"2026-05-09T21:48:01.133776Z","end":"2026-05-09T21:48:01.781779Z","steps":["trace[662849852] 'agreement among raft nodes before linearized reading' (duration: 647.790833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:01.781843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:01.133762Z","time spent":"648.071702ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T21:48:01.781903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.422216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-09T21:48:01.781944Z","caller":"traceutil/trace.go:171","msg":"trace[995079591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"672.452217ms","start":"2026-05-09T21:48:01.109474Z","end":"2026-05-09T21:48:01.781926Z","steps":["trace[995079591] 'agreement among raft nodes before linearized reading' (duration: 672.387085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:01.781976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:01.109421Z","time spent":"672.54844ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T21:48:01.782056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.400712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:01.782097Z","caller":"traceutil/trace.go:171","msg":"trace[1350163640] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1310; }","duration":"682.478894ms","start":"2026-05-09T21:48:01.099606Z","end":"2026-05-09T21:48:01.782085Z","steps":["trace[1350163640] 'agreement among raft nodes before linearized reading' (duration: 682.403912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:01.782134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:01.099586Z","time spent":"682.539406ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:48:01.782257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.394498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:01.782287Z","caller":"traceutil/trace.go:171","msg":"trace[484115427] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1311; }","duration":"250.46205ms","start":"2026-05-09T21:48:01.531819Z","end":"2026-05-09T21:48:01.782281Z","steps":["trace[484115427] 'agreement among raft nodes before linearized reading' (duration: 250.405859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:01.782467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.359644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-09T21:48:01.782496Z","caller":"traceutil/trace.go:171","msg":"trace[1529061190] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"272.620963ms","start":"2026-05-09T21:48:01.509867Z","end":"2026-05-09T21:48:01.782488Z","steps":["trace[1529061190] 'process raft request' (duration: 272.317213ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:01.782507Z","caller":"traceutil/trace.go:171","msg":"trace[1327435811] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1311; }","duration":"245.434406ms","start":"2026-05-09T21:48:01.537063Z","end":"2026-05-09T21:48:01.782498Z","steps":["trace[1327435811] 'agreement among raft nodes before linearized reading' (duration: 245.334923ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:06.46817Z","caller":"traceutil/trace.go:171","msg":"trace[1955731059] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"106.633448ms","start":"2026-05-09T21:48:06.361513Z","end":"2026-05-09T21:48:06.468147Z","steps":["trace[1955731059] 'process raft request' (duration: 106.398261ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:12.440552Z","caller":"traceutil/trace.go:171","msg":"trace[1126450809] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"100.604362ms","start":"2026-05-09T21:48:12.339929Z","end":"2026-05-09T21:48:12.440534Z","steps":["trace[1126450809] 'process raft request' (duration: 100.468487ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:20.119065Z","caller":"traceutil/trace.go:171","msg":"trace[484763127] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"168.143884ms","start":"2026-05-09T21:48:19.950897Z","end":"2026-05-09T21:48:20.119041Z","steps":["trace[484763127] 'process raft request' (duration: 167.73453ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:20.119323Z","caller":"traceutil/trace.go:171","msg":"trace[397315838] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"167.609906ms","start":"2026-05-09T21:48:19.951679Z","end":"2026-05-09T21:48:20.119289Z","steps":["trace[397315838] 'process raft request' (duration: 167.322697ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:20.119488Z","caller":"traceutil/trace.go:171","msg":"trace[1704513127] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"166.138678ms","start":"2026-05-09T21:48:19.95333Z","end":"2026-05-09T21:48:20.119469Z","steps":["trace[1704513127] 'process raft request' (duration: 166.099746ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:20.119603Z","caller":"traceutil/trace.go:171","msg":"trace[475049028] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"167.125131ms","start":"2026-05-09T21:48:19.952467Z","end":"2026-05-09T21:48:20.119592Z","steps":["trace[475049028] 'process raft request' (duration: 166.677856ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:30.337307Z","caller":"traceutil/trace.go:171","msg":"trace[1866337907] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"127.264063ms","start":"2026-05-09T21:48:30.210007Z","end":"2026-05-09T21:48:30.337271Z","steps":["trace[1866337907] 'process raft request' (duration: 87.603649ms)","trace[1866337907] 'compare' (duration: 39.527469ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:30.337543Z","caller":"traceutil/trace.go:171","msg":"trace[15743518] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"126.771836ms","start":"2026-05-09T21:48:30.210746Z","end":"2026-05-09T21:48:30.337518Z","steps":["trace[15743518] 'process raft request' (duration: 126.729354ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:30.337712Z","caller":"traceutil/trace.go:171","msg":"trace[442540296] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"127.001294ms","start":"2026-05-09T21:48:30.210701Z","end":"2026-05-09T21:48:30.337702Z","steps":["trace[442540296] 'process raft request' (duration: 126.678223ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:36.511113Z","caller":"traceutil/trace.go:171","msg":"trace[154833640] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"143.988551ms","start":"2026-05-09T21:48:36.367092Z","end":"2026-05-09T21:48:36.51108Z","steps":["trace[154833640] 'process raft request' (duration: 112.913549ms)","trace[154833640] 'compare' (duration: 30.868985ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:36.511251Z","caller":"traceutil/trace.go:171","msg":"trace[111170051] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"140.588452ms","start":"2026-05-09T21:48:36.370639Z","end":"2026-05-09T21:48:36.511228Z","steps":["trace[111170051] 'process raft request' (duration: 140.386785ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:42.870147Z","caller":"traceutil/trace.go:171","msg":"trace[851746039] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"132.346826ms","start":"2026-05-09T21:48:42.737757Z","end":"2026-05-09T21:48:42.870104Z","steps":["trace[851746039] 'process raft request' (duration: 132.16127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:43.079015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.658388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T21:48:43.079096Z","caller":"traceutil/trace.go:171","msg":"trace[1248549393] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1503; }","duration":"128.815693ms","start":"2026-05-09T21:48:42.950262Z","end":"2026-05-09T21:48:43.079078Z","steps":["trace[1248549393] 'range keys from in-memory index tree' (duration: 128.443991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:48.546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.583022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T21:48:48.54608Z","caller":"traceutil/trace.go:171","msg":"trace[354484179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1525; }","duration":"149.719186ms","start":"2026-05-09T21:48:48.39634Z","end":"2026-05-09T21:48:48.546059Z","steps":["trace[354484179] 'range keys from in-memory index tree' (duration: 149.399386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:48.546092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.88323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-09T21:48:48.546372Z","caller":"traceutil/trace.go:171","msg":"trace[520271730] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1525; }","duration":"118.20501ms","start":"2026-05-09T21:48:48.428135Z","end":"2026-05-09T21:48:48.54634Z","steps":["trace[520271730] 'range keys from in-memory index tree' (duration: 117.718004ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:48.692215Z","caller":"traceutil/trace.go:171","msg":"trace[1096502917] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"102.912767ms","start":"2026-05-09T21:48:48.589279Z","end":"2026-05-09T21:48:48.692192Z","steps":["trace[1096502917] 'process raft request' (duration: 102.848925ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:48.692286Z","caller":"traceutil/trace.go:171","msg":"trace[240504152] transaction","detail":"{read_only:false; response_revision:1528; number_of_response:1; }","duration":"103.162454ms","start":"2026-05-09T21:48:48.589079Z","end":"2026-05-09T21:48:48.692242Z","steps":["trace[240504152] 'process raft request' (duration: 89.017758ms)","trace[240504152] 'compare' (duration: 13.874108ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:58.926511Z","caller":"traceutil/trace.go:171","msg":"trace[2010872117] linearizableReadLoop","detail":"{readStateIndex:1640; appliedIndex:1639; }","duration":"184.450896ms","start":"2026-05-09T21:48:58.742039Z","end":"2026-05-09T21:48:58.92649Z","steps":["trace[2010872117] 'read index received' (duration: 84.740762ms)","trace[2010872117] 'applied index is now lower than readState.Index' (duration: 99.709544ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:58.926557Z","caller":"traceutil/trace.go:171","msg":"trace[936299082] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"194.651774ms","start":"2026-05-09T21:48:58.731874Z","end":"2026-05-09T21:48:58.926526Z","steps":["trace[936299082] 'process raft request' (duration: 94.959711ms)","trace[936299082] 'compare' (duration: 99.56855ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:48:58.926688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.638392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T21:48:58.926717Z","caller":"traceutil/trace.go:171","msg":"trace[1139836863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1574; }","duration":"184.712414ms","start":"2026-05-09T21:48:58.741997Z","end":"2026-05-09T21:48:58.926709Z","steps":["trace[1139836863] 'agreement among raft nodes before linearized reading' (duration: 184.593121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:58.926704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.456087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T21:48:58.926763Z","caller":"traceutil/trace.go:171","msg":"trace[1860676440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1574; }","duration":"184.536959ms","start":"2026-05-09T21:48:58.742211Z","end":"2026-05-09T21:48:58.926748Z","steps":["trace[1860676440] 'agreement among raft nodes before linearized reading' (duration: 184.400754ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:59.118768Z","caller":"traceutil/trace.go:171","msg":"trace[1338993230] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"188.175597ms","start":"2026-05-09T21:48:58.930562Z","end":"2026-05-09T21:48:59.118737Z","steps":["trace[1338993230] 'process raft request' (duration: 142.912417ms)","trace[1338993230] 'compare' (duration: 45.130925ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:09.153237Z","caller":"traceutil/trace.go:171","msg":"trace[926390963] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"120.061907ms","start":"2026-05-09T21:51:09.033139Z","end":"2026-05-09T21:51:09.153201Z","steps":["trace[926390963] 'process raft request' (duration: 119.702406ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:09.851775Z","caller":"traceutil/trace.go:171","msg":"trace[787463402] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2286; }","duration":"208.361192ms","start":"2026-05-09T21:51:09.643393Z","end":"2026-05-09T21:51:09.851754Z","steps":["trace[787463402] 'read index received' (duration: 199.875759ms)","trace[787463402] 'applied index is now lower than readState.Index' (duration: 8.484543ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:09.851873Z","caller":"traceutil/trace.go:171","msg":"trace[1304866472] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"230.603729ms","start":"2026-05-09T21:51:09.621232Z","end":"2026-05-09T21:51:09.851835Z","steps":["trace[1304866472] 'process raft request' (duration: 222.067364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:09.852057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.954946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-09T21:51:09.852109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.629621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-09T21:51:09.8522Z","caller":"traceutil/trace.go:171","msg":"trace[1579777657] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2194; }","duration":"208.838507ms","start":"2026-05-09T21:51:09.643348Z","end":"2026-05-09T21:51:09.852187Z","steps":["trace[1579777657] 'agreement among raft nodes before linearized reading' (duration: 208.520927ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:09.852146Z","caller":"traceutil/trace.go:171","msg":"trace[612873924] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:2194; }","duration":"139.093931ms","start":"2026-05-09T21:51:09.71303Z","end":"2026-05-09T21:51:09.852124Z","steps":["trace[612873924] 'agreement among raft nodes before linearized reading' (duration: 138.880434ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:17.537879Z","caller":"traceutil/trace.go:171","msg":"trace[8586071] linearizableReadLoop","detail":"{readStateIndex:2330; appliedIndex:2329; }","duration":"355.426629ms","start":"2026-05-09T21:51:17.182429Z","end":"2026-05-09T21:51:17.537856Z","steps":["trace[8586071] 'read index received' (duration: 355.235393ms)","trace[8586071] 'applied index is now lower than readState.Index' (duration: 190.306µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:17.538016Z","caller":"traceutil/trace.go:171","msg":"trace[1258951225] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"357.114083ms","start":"2026-05-09T21:51:17.180868Z","end":"2026-05-09T21:51:17.537982Z","steps":["trace[1258951225] 'process raft request' (duration: 356.853765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:17.538175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:51:17.180829Z","time spent":"357.259418ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:51:17.538232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.738194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T21:51:17.538314Z","caller":"traceutil/trace.go:171","msg":"trace[526145524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2235; }","duration":"330.866158ms","start":"2026-05-09T21:51:17.207431Z","end":"2026-05-09T21:51:17.538297Z","steps":["trace[526145524] 'agreement among raft nodes before linearized reading' (duration: 330.632941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:17.538362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.181539ms","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:526"} {"level":"warn","ts":"2026-05-09T21:51:17.53838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:51:17.207414Z","time spent":"330.95096ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T21:51:17.538407Z","caller":"traceutil/trace.go:171","msg":"trace[869598230] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2235; }","duration":"187.236291ms","start":"2026-05-09T21:51:17.351152Z","end":"2026-05-09T21:51:17.538389Z","steps":["trace[869598230] 'agreement among raft nodes before linearized reading' (duration: 187.132458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:17.538238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.775447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T21:51:17.538524Z","caller":"traceutil/trace.go:171","msg":"trace[1471581823] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2235; }","duration":"187.095976ms","start":"2026-05-09T21:51:17.351413Z","end":"2026-05-09T21:51:17.538509Z","steps":["trace[1471581823] 'agreement among raft nodes before linearized reading' (duration: 186.751295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:17.539352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.916937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T21:51:17.539387Z","caller":"traceutil/trace.go:171","msg":"trace[698599889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2235; }","duration":"356.993599ms","start":"2026-05-09T21:51:17.182384Z","end":"2026-05-09T21:51:17.539378Z","steps":["trace[698599889] 'agreement among raft nodes before linearized reading' (duration: 355.629925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:17.539408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:51:17.182364Z","time spent":"357.038611ms","remote":"127.0.0.1:48896","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\" "}