{"level":"warn","ts":"2026-04-21T01:27:03.996498Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T01:27:03.996892Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-21T01:27:03.997042Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T01:27:03.9971Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-21T01:27:03.997166Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T01:27:03.997852Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-04-21T01:27:03.99798Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-21T01:27:04.160005Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"161.737919ms"} {"level":"info","ts":"2026-04-21T01:27:04.180203Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-04-21T01:27:04.180309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T01:27:04.180341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-04-21T01:27:04.180366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T01:27:04.18038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-04-21T01:27:04.180434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-04-21T01:27:04.194755Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T01:27:04.196389Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T01:27:04.198496Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-21T01:27:04.201684Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T01:27:04.209311Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-21T01:27:04.209362Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-21T01:27:04.209199Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T01:27:04.209266Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T01:27:04.210473Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T01:27:04.210572Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T01:27:04.210596Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T01:27:04.211124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-04-21T01:27:04.2111Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T01:27:04.211166Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T01:27:04.211495Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-21T01:27:05.081366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T01:27:05.081416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T01:27:05.081446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-04-21T01:27:05.081461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-04-21T01:27:05.081469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-21T01:27:05.081485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-04-21T01:27:05.081495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-21T01:27:05.083605Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T01:27:05.08365Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T01:27:05.083618Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T01:27:05.083666Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T01:27:05.083851Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T01:27:05.083985Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T01:27:05.08473Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T01:27:05.084909Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T01:27:05.084955Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T01:27:05.085431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T01:27:05.087146Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-04-21T01:27:08.809266Z","caller":"traceutil/trace.go:171","msg":"trace[872073463] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"193.640963ms","start":"2026-04-21T01:27:08.615597Z","end":"2026-04-21T01:27:08.809238Z","steps":["trace[872073463] 'process raft request' (duration: 193.466679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:09.779963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.311233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:27:09.78009Z","caller":"traceutil/trace.go:171","msg":"trace[132433576] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:251; }","duration":"634.793379ms","start":"2026-04-21T01:27:09.145279Z","end":"2026-04-21T01:27:09.780072Z","steps":["trace[132433576] 'read index received' (duration: 45.271µs)","trace[132433576] 'applied index is now lower than readState.Index' (duration: 634.746458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:27:09.780182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.903011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:09.780208Z","caller":"traceutil/trace.go:171","msg":"trace[1362561897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:246; }","duration":"634.980543ms","start":"2026-04-21T01:27:09.145219Z","end":"2026-04-21T01:27:09.7802Z","steps":["trace[1362561897] 'agreement among raft nodes before linearized reading' (duration: 634.900881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:09.780243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:09.145178Z","time spent":"635.057025ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T01:27:09.780265Z","caller":"traceutil/trace.go:171","msg":"trace[1626267338] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"961.675176ms","start":"2026-04-21T01:27:08.818528Z","end":"2026-04-21T01:27:09.780203Z","steps":["trace[1626267338] 'process raft request' (duration: 145.503735ms)","trace[1626267338] 'compare' (duration: 815.101109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:27:09.780578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:08.818511Z","time spent":"961.85725ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T01:27:10.716473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.181527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:27:10.716614Z","caller":"traceutil/trace.go:171","msg":"trace[1202893629] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"931.273178ms","start":"2026-04-21T01:27:09.785314Z","end":"2026-04-21T01:27:10.716587Z","steps":["trace[1202893629] 'read index received' (duration: 429.776844ms)","trace[1202893629] 'applied index is now lower than readState.Index' (duration: 501.494354ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:27:10.716718Z","caller":"traceutil/trace.go:171","msg":"trace[1990759024] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"932.329341ms","start":"2026-04-21T01:27:09.784377Z","end":"2026-04-21T01:27:10.716707Z","steps":["trace[1990759024] 'process raft request' (duration: 430.657863ms)","trace[1990759024] 'compare' (duration: 500.944523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:27:10.716858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.119503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:10.717Z","caller":"traceutil/trace.go:171","msg":"trace[2117020088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:247; }","duration":"834.321078ms","start":"2026-04-21T01:27:09.882655Z","end":"2026-04-21T01:27:10.716976Z","steps":["trace[2117020088] 'agreement among raft nodes before linearized reading' (duration: 834.066193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:10.717046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:09.882625Z","time spent":"834.41182ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T01:27:10.717343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.998244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-295gyl\" ","response":"range_response_count:1 size:540"} {"level":"warn","ts":"2026-04-21T01:27:10.717375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:09.784348Z","time spent":"932.396892ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T01:27:10.717457Z","caller":"traceutil/trace.go:171","msg":"trace[511944352] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-295gyl; range_end:; response_count:1; response_revision:247; }","duration":"932.159267ms","start":"2026-04-21T01:27:09.785268Z","end":"2026-04-21T01:27:10.717427Z","steps":["trace[511944352] 'agreement among raft nodes before linearized reading' (duration: 931.932053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:10.717798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:09.785245Z","time spent":"932.541215ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":564,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-295gyl\" "} {"level":"warn","ts":"2026-04-21T01:27:11.150883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.62701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-04-21T01:27:11.150956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.75558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-21T01:27:11.150997Z","caller":"traceutil/trace.go:171","msg":"trace[565262359] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:268; }","duration":"176.813324ms","start":"2026-04-21T01:27:10.974158Z","end":"2026-04-21T01:27:11.150972Z","steps":["trace[565262359] 'range keys from in-memory index tree' (duration: 176.482057ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:11.15105Z","caller":"traceutil/trace.go:171","msg":"trace[1955017948] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:268; }","duration":"227.920745ms","start":"2026-04-21T01:27:10.923104Z","end":"2026-04-21T01:27:11.151025Z","steps":["trace[1955017948] 'range keys from in-memory index tree' (duration: 227.594668ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.407144Z","caller":"traceutil/trace.go:171","msg":"trace[81841458] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"189.795751ms","start":"2026-04-21T01:27:36.21732Z","end":"2026-04-21T01:27:36.407116Z","steps":["trace[81841458] 'process raft request' (duration: 189.601457ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.488148Z","caller":"traceutil/trace.go:171","msg":"trace[581859983] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"265.209041ms","start":"2026-04-21T01:27:36.222873Z","end":"2026-04-21T01:27:36.488082Z","steps":["trace[581859983] 'process raft request' (duration: 264.819102ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.488222Z","caller":"traceutil/trace.go:171","msg":"trace[1499991152] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"261.43566ms","start":"2026-04-21T01:27:36.226747Z","end":"2026-04-21T01:27:36.488182Z","steps":["trace[1499991152] 'process raft request' (duration: 261.279756ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.488269Z","caller":"traceutil/trace.go:171","msg":"trace[1023820072] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"245.704996ms","start":"2026-04-21T01:27:36.24255Z","end":"2026-04-21T01:27:36.488255Z","steps":["trace[1023820072] 'process raft request' (duration: 245.530402ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.488153Z","caller":"traceutil/trace.go:171","msg":"trace[38751788] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"261.352009ms","start":"2026-04-21T01:27:36.226733Z","end":"2026-04-21T01:27:36.488085Z","steps":["trace[38751788] 'process raft request' (duration: 261.239796ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.488441Z","caller":"traceutil/trace.go:171","msg":"trace[1976286532] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"245.740086ms","start":"2026-04-21T01:27:36.242639Z","end":"2026-04-21T01:27:36.488379Z","steps":["trace[1976286532] 'process raft request' (duration: 245.569243ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.488561Z","caller":"traceutil/trace.go:171","msg":"trace[326145880] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"245.854979ms","start":"2026-04-21T01:27:36.242693Z","end":"2026-04-21T01:27:36.488548Z","steps":["trace[326145880] 'process raft request' (duration: 245.565783ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.488762Z","caller":"traceutil/trace.go:171","msg":"trace[1892509889] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"245.978851ms","start":"2026-04-21T01:27:36.242766Z","end":"2026-04-21T01:27:36.488745Z","steps":["trace[1892509889] 'process raft request' (duration: 245.659094ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.488806Z","caller":"traceutil/trace.go:171","msg":"trace[2142050168] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"228.007437ms","start":"2026-04-21T01:27:36.260788Z","end":"2026-04-21T01:27:36.488795Z","steps":["trace[2142050168] 'process raft request' (duration: 227.769392ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.488921Z","caller":"traceutil/trace.go:171","msg":"trace[2073068268] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"226.300071ms","start":"2026-04-21T01:27:36.262607Z","end":"2026-04-21T01:27:36.488907Z","steps":["trace[2073068268] 'process raft request' (duration: 226.108407ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.48909Z","caller":"traceutil/trace.go:171","msg":"trace[335952398] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"196.32717ms","start":"2026-04-21T01:27:36.292734Z","end":"2026-04-21T01:27:36.489061Z","steps":["trace[335952398] 'process raft request' (duration: 196.077035ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.489075Z","caller":"traceutil/trace.go:171","msg":"trace[1308528836] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:521; }","duration":"240.762489ms","start":"2026-04-21T01:27:36.248276Z","end":"2026-04-21T01:27:36.489038Z","steps":["trace[1308528836] 'read index received' (duration: 158.358929ms)","trace[1308528836] 'applied index is now lower than readState.Index' (duration: 82.40197ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:27:36.489379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.000384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:36.489439Z","caller":"traceutil/trace.go:171","msg":"trace[63469852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"241.177798ms","start":"2026-04-21T01:27:36.248247Z","end":"2026-04-21T01:27:36.489425Z","steps":["trace[63469852] 'agreement among raft nodes before linearized reading' (duration: 240.890002ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.641214Z","caller":"traceutil/trace.go:171","msg":"trace[283022040] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"127.498591ms","start":"2026-04-21T01:27:36.513691Z","end":"2026-04-21T01:27:36.641189Z","steps":["trace[283022040] 'process raft request' (duration: 118.763215ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.641385Z","caller":"traceutil/trace.go:171","msg":"trace[1339771034] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"127.614094ms","start":"2026-04-21T01:27:36.513741Z","end":"2026-04-21T01:27:36.641355Z","steps":["trace[1339771034] 'process raft request' (duration: 127.294207ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.642054Z","caller":"traceutil/trace.go:171","msg":"trace[1157439308] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"128.098984ms","start":"2026-04-21T01:27:36.513931Z","end":"2026-04-21T01:27:36.64203Z","steps":["trace[1157439308] 'process raft request' (duration: 127.601293ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.642799Z","caller":"traceutil/trace.go:171","msg":"trace[1857397740] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"117.231423ms","start":"2026-04-21T01:27:36.525537Z","end":"2026-04-21T01:27:36.642769Z","steps":["trace[1857397740] 'process raft request' (duration: 117.153941ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.643016Z","caller":"traceutil/trace.go:171","msg":"trace[1124001825] transaction","detail":"{read_only:false; number_of_response:0; response_revision:527; }","duration":"124.747812ms","start":"2026-04-21T01:27:36.518189Z","end":"2026-04-21T01:27:36.642937Z","steps":["trace[1124001825] 'process raft request' (duration: 123.812032ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.643205Z","caller":"traceutil/trace.go:171","msg":"trace[1594438131] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"120.657686ms","start":"2026-04-21T01:27:36.522535Z","end":"2026-04-21T01:27:36.643193Z","steps":["trace[1594438131] 'process raft request' (duration: 119.964331ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.670358Z","caller":"traceutil/trace.go:171","msg":"trace[1008710627] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"135.83747ms","start":"2026-04-21T01:27:36.534478Z","end":"2026-04-21T01:27:36.670315Z","steps":["trace[1008710627] 'process raft request' (duration: 135.169525ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.673051Z","caller":"traceutil/trace.go:171","msg":"trace[1372832863] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"125.771895ms","start":"2026-04-21T01:27:36.544576Z","end":"2026-04-21T01:27:36.670347Z","steps":["trace[1372832863] 'process raft request' (duration: 125.245723ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.799306Z","caller":"traceutil/trace.go:171","msg":"trace[1179199142] transaction","detail":"{read_only:false; number_of_response:1; response_revision:533; }","duration":"144.770071ms","start":"2026-04-21T01:27:36.654509Z","end":"2026-04-21T01:27:36.799279Z","steps":["trace[1179199142] 'process raft request' (duration: 115.162549ms)","trace[1179199142] 'compare' (duration: 29.428328ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:27:36.799564Z","caller":"traceutil/trace.go:171","msg":"trace[897466025] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"143.940183ms","start":"2026-04-21T01:27:36.655582Z","end":"2026-04-21T01:27:36.799522Z","steps":["trace[897466025] 'process raft request' (duration: 143.673068ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.800296Z","caller":"traceutil/trace.go:171","msg":"trace[1503996662] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"137.570097ms","start":"2026-04-21T01:27:36.662705Z","end":"2026-04-21T01:27:36.800275Z","steps":["trace[1503996662] 'process raft request' (duration: 136.78223ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.800597Z","caller":"traceutil/trace.go:171","msg":"trace[816554819] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"113.851921ms","start":"2026-04-21T01:27:36.68671Z","end":"2026-04-21T01:27:36.800562Z","steps":["trace[816554819] 'process raft request' (duration: 113.40234ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.801162Z","caller":"traceutil/trace.go:171","msg":"trace[69966968] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"110.52471ms","start":"2026-04-21T01:27:36.690624Z","end":"2026-04-21T01:27:36.801149Z","steps":["trace[69966968] 'process raft request' (duration: 109.877996ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.801566Z","caller":"traceutil/trace.go:171","msg":"trace[1337692864] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"106.169416ms","start":"2026-04-21T01:27:36.695362Z","end":"2026-04-21T01:27:36.801531Z","steps":["trace[1337692864] 'process raft request' (duration: 105.761937ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:36.801699Z","caller":"traceutil/trace.go:171","msg":"trace[1777135223] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"103.876858ms","start":"2026-04-21T01:27:36.697807Z","end":"2026-04-21T01:27:36.801684Z","steps":["trace[1777135223] 'process raft request' (duration: 103.599502ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:42.612435Z","caller":"traceutil/trace.go:171","msg":"trace[274381491] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"518.082679ms","start":"2026-04-21T01:27:42.09433Z","end":"2026-04-21T01:27:42.612413Z","steps":["trace[274381491] 'process raft request' (duration: 516.797361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:42.612797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:42.094306Z","time spent":"518.204631ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:27:43.269172Z","caller":"traceutil/trace.go:171","msg":"trace[827291741] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"320.736226ms","start":"2026-04-21T01:27:42.948395Z","end":"2026-04-21T01:27:43.269132Z","steps":["trace[827291741] 'process raft request' (duration: 320.519151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:43.269597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:42.948385Z","time spent":"320.91681ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:27:43.534962Z","caller":"traceutil/trace.go:171","msg":"trace[1865985383] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"586.430696ms","start":"2026-04-21T01:27:42.948479Z","end":"2026-04-21T01:27:43.53491Z","steps":["trace[1865985383] 'process raft request' (duration: 570.122649ms)","trace[1865985383] 'compare' (duration: 15.792967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:27:43.535181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:42.948463Z","time spent":"586.610261ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:27:43.536597Z","caller":"traceutil/trace.go:171","msg":"trace[1913738382] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"431.372238ms","start":"2026-04-21T01:27:43.105169Z","end":"2026-04-21T01:27:43.536541Z","steps":["trace[1913738382] 'process raft request' (duration: 429.983388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:43.536844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:43.105134Z","time spent":"431.567241ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:27:43.582346Z","caller":"traceutil/trace.go:171","msg":"trace[1134467356] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:635; }","duration":"334.028119ms","start":"2026-04-21T01:27:43.248291Z","end":"2026-04-21T01:27:43.582319Z","steps":["trace[1134467356] 'read index received' (duration: 20.736943ms)","trace[1134467356] 'applied index is now lower than readState.Index' (duration: 313.289716ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:27:43.582462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.154122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:43.582503Z","caller":"traceutil/trace.go:171","msg":"trace[1278252060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"334.244364ms","start":"2026-04-21T01:27:43.248247Z","end":"2026-04-21T01:27:43.582492Z","steps":["trace[1278252060] 'agreement among raft nodes before linearized reading' (duration: 334.161692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:43.582535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:43.2482Z","time spent":"334.328666ms","remote":"127.0.0.1:42158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:27:44.74807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626005878831375,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T01:27:45.248453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626005878831375,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T01:27:45.45516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:45.026876Z","time spent":"428.277401ms","remote":"127.0.0.1:53356","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-21T01:27:45.489136Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.403735152s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T01:27:45.490068Z","caller":"traceutil/trace.go:171","msg":"trace[1624277114] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"1.242758336s","start":"2026-04-21T01:27:44.247288Z","end":"2026-04-21T01:27:45.490047Z","steps":["trace[1624277114] 'read index received' (duration: 1.241966219s)","trace[1624277114] 'applied index is now lower than readState.Index' (duration: 791.237µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:27:45.490202Z","caller":"traceutil/trace.go:171","msg":"trace[1134201125] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.406555472s","start":"2026-04-21T01:27:44.083635Z","end":"2026-04-21T01:27:45.490191Z","steps":["trace[1134201125] 'process raft request' (duration: 1.405852377s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.490313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:44.083604Z","time spent":"1.406627694s","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:27:45.490463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243159595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.490542Z","caller":"traceutil/trace.go:171","msg":"trace[153464933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"1.243289877s","start":"2026-04-21T01:27:44.247233Z","end":"2026-04-21T01:27:45.490523Z","steps":["trace[153464933] 'agreement among raft nodes before linearized reading' (duration: 1.243121604s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.490638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:44.247212Z","time spent":"1.24341415s","remote":"127.0.0.1:42168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:27:45.490665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.545154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.49076Z","caller":"traceutil/trace.go:171","msg":"trace[1516344172] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:621; }","duration":"247.693887ms","start":"2026-04-21T01:27:45.243042Z","end":"2026-04-21T01:27:45.490736Z","steps":["trace[1516344172] 'agreement among raft nodes before linearized reading' (duration: 247.524303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.490771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.839153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.490807Z","caller":"traceutil/trace.go:171","msg":"trace[474079582] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:621; }","duration":"220.889404ms","start":"2026-04-21T01:27:45.269906Z","end":"2026-04-21T01:27:45.490796Z","steps":["trace[474079582] 'agreement among raft nodes before linearized reading' (duration: 220.812732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.490961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.56154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T01:27:45.490998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.636202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.491038Z","caller":"traceutil/trace.go:171","msg":"trace[782488104] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:621; }","duration":"221.687453ms","start":"2026-04-21T01:27:45.26934Z","end":"2026-04-21T01:27:45.491027Z","steps":["trace[782488104] 'agreement among raft nodes before linearized reading' (duration: 221.629211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.491125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.79905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.491147Z","caller":"traceutil/trace.go:171","msg":"trace[992411047] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:621; }","duration":"203.834991ms","start":"2026-04-21T01:27:45.287306Z","end":"2026-04-21T01:27:45.491141Z","steps":["trace[992411047] 'agreement among raft nodes before linearized reading' (duration: 203.80248ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:45.49102Z","caller":"traceutil/trace.go:171","msg":"trace[645284456] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:621; }","duration":"221.642432ms","start":"2026-04-21T01:27:45.269362Z","end":"2026-04-21T01:27:45.491004Z","steps":["trace[645284456] 'agreement among raft nodes before linearized reading' (duration: 221.244803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.491175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.775598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.491208Z","caller":"traceutil/trace.go:171","msg":"trace[617939833] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:621; }","duration":"246.820549ms","start":"2026-04-21T01:27:45.244379Z","end":"2026-04-21T01:27:45.4912Z","steps":["trace[617939833] 'agreement among raft nodes before linearized reading' (duration: 246.773798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.491344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.376372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T01:27:45.491385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.311285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.491402Z","caller":"traceutil/trace.go:171","msg":"trace[1765718015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:621; }","duration":"274.371796ms","start":"2026-04-21T01:27:45.217025Z","end":"2026-04-21T01:27:45.491397Z","steps":["trace[1765718015] 'agreement among raft nodes before linearized reading' (duration: 274.341756ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:45.491396Z","caller":"traceutil/trace.go:171","msg":"trace[1227976656] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:621; }","duration":"204.432913ms","start":"2026-04-21T01:27:45.286951Z","end":"2026-04-21T01:27:45.491384Z","steps":["trace[1227976656] 'agreement among raft nodes before linearized reading' (duration: 204.360031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.491486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.543876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.491504Z","caller":"traceutil/trace.go:171","msg":"trace[1717304646] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:621; }","duration":"248.574986ms","start":"2026-04-21T01:27:45.242923Z","end":"2026-04-21T01:27:45.491498Z","steps":["trace[1717304646] 'agreement among raft nodes before linearized reading' (duration: 248.547926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.491569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.689972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.491585Z","caller":"traceutil/trace.go:171","msg":"trace[721044484] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:621; }","duration":"273.707492ms","start":"2026-04-21T01:27:45.217874Z","end":"2026-04-21T01:27:45.491581Z","steps":["trace[721044484] 'agreement among raft nodes before linearized reading' (duration: 273.679001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.491623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.857332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.491672Z","caller":"traceutil/trace.go:171","msg":"trace[849456059] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:621; }","duration":"204.910824ms","start":"2026-04-21T01:27:45.286742Z","end":"2026-04-21T01:27:45.491653Z","steps":["trace[849456059] 'agreement among raft nodes before linearized reading' (duration: 204.853432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.491695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.664462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:27:45.49171Z","caller":"traceutil/trace.go:171","msg":"trace[1774740819] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:621; }","duration":"274.709284ms","start":"2026-04-21T01:27:45.216996Z","end":"2026-04-21T01:27:45.491705Z","steps":["trace[1774740819] 'agreement among raft nodes before linearized reading' (duration: 274.685183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.491794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.569974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T01:27:45.491833Z","caller":"traceutil/trace.go:171","msg":"trace[1753857842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"544.630895ms","start":"2026-04-21T01:27:44.947193Z","end":"2026-04-21T01:27:45.491824Z","steps":["trace[1753857842] 'agreement among raft nodes before linearized reading' (duration: 544.518983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.491861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:44.947181Z","time spent":"544.673436ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T01:27:45.492067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.092535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T01:27:45.492148Z","caller":"traceutil/trace.go:171","msg":"trace[1480125770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"218.191366ms","start":"2026-04-21T01:27:45.273942Z","end":"2026-04-21T01:27:45.492134Z","steps":["trace[1480125770] 'agreement among raft nodes before linearized reading' (duration: 216.496991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.49311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.697666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T01:27:45.493189Z","caller":"traceutil/trace.go:171","msg":"trace[1590748901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"589.824789ms","start":"2026-04-21T01:27:44.903351Z","end":"2026-04-21T01:27:45.493176Z","steps":["trace[1590748901] 'agreement among raft nodes before linearized reading' (duration: 587.04263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:27:45.493228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:44.903327Z","time spent":"589.893421ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T01:27:47.296835Z","caller":"traceutil/trace.go:171","msg":"trace[124102103] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"265.520287ms","start":"2026-04-21T01:27:47.031278Z","end":"2026-04-21T01:27:47.296798Z","steps":["trace[124102103] 'process raft request' (duration: 265.355734ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:27:47.300843Z","caller":"traceutil/trace.go:171","msg":"trace[1696411455] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"209.242736ms","start":"2026-04-21T01:27:47.091566Z","end":"2026-04-21T01:27:47.300809Z","steps":["trace[1696411455] 'process raft request' (duration: 209.017281ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:28:00.711434Z","caller":"traceutil/trace.go:171","msg":"trace[1304675950] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"463.950623ms","start":"2026-04-21T01:28:00.24746Z","end":"2026-04-21T01:28:00.711411Z","steps":["trace[1304675950] 'read index received' (duration: 463.730898ms)","trace[1304675950] 'applied index is now lower than readState.Index' (duration: 218.645µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:28:00.71147Z","caller":"traceutil/trace.go:171","msg":"trace[840391402] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"769.74883ms","start":"2026-04-21T01:27:59.941701Z","end":"2026-04-21T01:28:00.71145Z","steps":["trace[840391402] 'process raft request' (duration: 769.568497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:00.711565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.088945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:28:00.711601Z","caller":"traceutil/trace.go:171","msg":"trace[1044264045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"464.175977ms","start":"2026-04-21T01:28:00.247411Z","end":"2026-04-21T01:28:00.711587Z","steps":["trace[1044264045] 'agreement among raft nodes before linearized reading' (duration: 464.106996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:00.711641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:00.247384Z","time spent":"464.247948ms","remote":"127.0.0.1:42168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:28:00.711642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:27:59.941676Z","time spent":"769.859262ms","remote":"127.0.0.1:42294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:28:35.156043Z","caller":"traceutil/trace.go:171","msg":"trace[1912356631] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"235.199573ms","start":"2026-04-21T01:28:34.920821Z","end":"2026-04-21T01:28:35.156021Z","steps":["trace[1912356631] 'read index received' (duration: 235.05908ms)","trace[1912356631] 'applied index is now lower than readState.Index' (duration: 139.713µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:28:35.15608Z","caller":"traceutil/trace.go:171","msg":"trace[2076833395] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"336.172094ms","start":"2026-04-21T01:28:34.819885Z","end":"2026-04-21T01:28:35.156057Z","steps":["trace[2076833395] 'process raft request' (duration: 336.006951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:35.156189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.348796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T01:28:35.156201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:34.819865Z","time spent":"336.266457ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:28:35.156222Z","caller":"traceutil/trace.go:171","msg":"trace[933259320] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:995; }","duration":"235.438328ms","start":"2026-04-21T01:28:34.920775Z","end":"2026-04-21T01:28:35.156214Z","steps":["trace[933259320] 'agreement among raft nodes before linearized reading' (duration: 235.364177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:35.86109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.405153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:28:35.861186Z","caller":"traceutil/trace.go:171","msg":"trace[1541665475] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"613.163182ms","start":"2026-04-21T01:28:35.248007Z","end":"2026-04-21T01:28:35.86117Z","steps":["trace[1541665475] 'read index received' (duration: 23.557405ms)","trace[1541665475] 'applied index is now lower than readState.Index' (duration: 589.604417ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:28:35.861237Z","caller":"traceutil/trace.go:171","msg":"trace[114376680] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"700.723056ms","start":"2026-04-21T01:28:35.160473Z","end":"2026-04-21T01:28:35.861196Z","steps":["trace[114376680] 'process raft request' (duration: 111.103508ms)","trace[114376680] 'compare' (duration: 589.131847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:28:35.861404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:35.160458Z","time spent":"700.853779ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:28:35.861274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.270324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:28:35.861506Z","caller":"traceutil/trace.go:171","msg":"trace[50675488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"613.51096ms","start":"2026-04-21T01:28:35.247973Z","end":"2026-04-21T01:28:35.861483Z","steps":["trace[50675488] 'agreement among raft nodes before linearized reading' (duration: 613.274135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:35.861583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:35.247955Z","time spent":"613.599631ms","remote":"127.0.0.1:42158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:28:35.861594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.254824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T01:28:35.861586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.87604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T01:28:35.861639Z","caller":"traceutil/trace.go:171","msg":"trace[1352130609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"113.314005ms","start":"2026-04-21T01:28:35.748311Z","end":"2026-04-21T01:28:35.861625Z","steps":["trace[1352130609] 'agreement among raft nodes before linearized reading' (duration: 113.190392ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:28:35.861687Z","caller":"traceutil/trace.go:171","msg":"trace[728206337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"108.981092ms","start":"2026-04-21T01:28:35.752666Z","end":"2026-04-21T01:28:35.861647Z","steps":["trace[728206337] 'agreement among raft nodes before linearized reading' (duration: 108.784688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:35.861738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.299014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T01:28:35.861831Z","caller":"traceutil/trace.go:171","msg":"trace[1821962198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"113.422197ms","start":"2026-04-21T01:28:35.748383Z","end":"2026-04-21T01:28:35.861806Z","steps":["trace[1821962198] 'agreement among raft nodes before linearized reading' (duration: 113.111731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:35.861871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.384683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T01:28:35.861915Z","caller":"traceutil/trace.go:171","msg":"trace[338343269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:996; }","duration":"118.459524ms","start":"2026-04-21T01:28:35.743444Z","end":"2026-04-21T01:28:35.861903Z","steps":["trace[338343269] 'agreement among raft nodes before linearized reading' (duration: 117.854162ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:28:58.108451Z","caller":"traceutil/trace.go:171","msg":"trace[1107945745] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"133.600999ms","start":"2026-04-21T01:28:57.97483Z","end":"2026-04-21T01:28:58.108431Z","steps":["trace[1107945745] 'process raft request' (duration: 133.474136ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:28:58.4184Z","caller":"traceutil/trace.go:171","msg":"trace[1338059984] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1105; }","duration":"438.623297ms","start":"2026-04-21T01:28:57.979751Z","end":"2026-04-21T01:28:58.418374Z","steps":["trace[1338059984] 'read index received' (duration: 128.642253ms)","trace[1338059984] 'applied index is now lower than readState.Index' (duration: 309.979934ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:28:58.418487Z","caller":"traceutil/trace.go:171","msg":"trace[1032956598] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"443.114034ms","start":"2026-04-21T01:28:57.975353Z","end":"2026-04-21T01:28:58.418467Z","steps":["trace[1032956598] 'process raft request' (duration: 442.853038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:58.418746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.454647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-21T01:28:58.418655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:57.97534Z","time spent":"443.190365ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:28:58.418791Z","caller":"traceutil/trace.go:171","msg":"trace[1891792874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1069; }","duration":"404.548739ms","start":"2026-04-21T01:28:58.014231Z","end":"2026-04-21T01:28:58.41878Z","steps":["trace[1891792874] 'agreement among raft nodes before linearized reading' (duration: 404.402576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:58.418831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:58.014205Z","time spent":"404.618161ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T01:28:58.418767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.996106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-21T01:28:58.418893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.621021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T01:28:58.418939Z","caller":"traceutil/trace.go:171","msg":"trace[121957904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1069; }","duration":"439.200481ms","start":"2026-04-21T01:28:57.979725Z","end":"2026-04-21T01:28:58.418925Z","steps":["trace[121957904] 'agreement among raft nodes before linearized reading' (duration: 438.873994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:58.418939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.550898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T01:28:58.418963Z","caller":"traceutil/trace.go:171","msg":"trace[625655214] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:1069; }","duration":"369.728053ms","start":"2026-04-21T01:28:58.049217Z","end":"2026-04-21T01:28:58.418945Z","steps":["trace[625655214] 'agreement among raft nodes before linearized reading' (duration: 369.505938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:58.418992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:57.979714Z","time spent":"439.269772ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T01:28:58.419014Z","caller":"traceutil/trace.go:171","msg":"trace[869726954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"404.665001ms","start":"2026-04-21T01:28:58.014332Z","end":"2026-04-21T01:28:58.418997Z","steps":["trace[869726954] 'agreement among raft nodes before linearized reading' (duration: 404.447447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:58.419019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:58.049203Z","time spent":"369.808015ms","remote":"127.0.0.1:42196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-04-21T01:28:58.41906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:58.014316Z","time spent":"404.736192ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T01:28:58.917879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.483898ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-21T01:28:58.918324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:58.419947Z","time spent":"498.368885ms","remote":"127.0.0.1:42196","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-21T01:28:58.918459Z","caller":"traceutil/trace.go:171","msg":"trace[637792727] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"152.291139ms","start":"2026-04-21T01:28:58.766149Z","end":"2026-04-21T01:28:58.91844Z","steps":["trace[637792727] 'process raft request' (duration: 152.251168ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:28:58.918538Z","caller":"traceutil/trace.go:171","msg":"trace[1195026709] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"494.355779ms","start":"2026-04-21T01:28:58.424158Z","end":"2026-04-21T01:28:58.918513Z","steps":["trace[1195026709] 'process raft request' (duration: 494.204076ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:28:58.918534Z","caller":"traceutil/trace.go:171","msg":"trace[1424037139] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"495.466753ms","start":"2026-04-21T01:28:58.423031Z","end":"2026-04-21T01:28:58.918498Z","steps":["trace[1424037139] 'process raft request' (duration: 495.152265ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:28:58.918558Z","caller":"traceutil/trace.go:171","msg":"trace[1060986630] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"494.981513ms","start":"2026-04-21T01:28:58.423538Z","end":"2026-04-21T01:28:58.91852Z","steps":["trace[1060986630] 'process raft request' (duration: 494.779789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:28:58.918701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:58.423011Z","time spent":"495.603296ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:28:58.918828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:58.423518Z","time spent":"495.211128ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:28:58.91866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:28:58.424147Z","time spent":"494.451161ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:29:00.552581Z","caller":"traceutil/trace.go:171","msg":"trace[1066823091] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"129.072022ms","start":"2026-04-21T01:29:00.423494Z","end":"2026-04-21T01:29:00.552566Z","steps":["trace[1066823091] 'read index received' (duration: 128.928049ms)","trace[1066823091] 'applied index is now lower than readState.Index' (duration: 143.323µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:00.55269Z","caller":"traceutil/trace.go:171","msg":"trace[1297428233] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"435.658603ms","start":"2026-04-21T01:29:00.116992Z","end":"2026-04-21T01:29:00.55265Z","steps":["trace[1297428233] 'process raft request' (duration: 435.469759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:00.552778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.275436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T01:29:00.552803Z","caller":"traceutil/trace.go:171","msg":"trace[1359301216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1076; }","duration":"129.358149ms","start":"2026-04-21T01:29:00.423438Z","end":"2026-04-21T01:29:00.552796Z","steps":["trace[1359301216] 'agreement among raft nodes before linearized reading' (duration: 129.212336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:00.5529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:00.116932Z","time spent":"435.866828ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:29:01.688371Z","caller":"traceutil/trace.go:171","msg":"trace[961953147] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"390.17155ms","start":"2026-04-21T01:29:01.298174Z","end":"2026-04-21T01:29:01.688346Z","steps":["trace[961953147] 'process raft request' (duration: 389.998797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:01.688706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:01.298152Z","time spent":"390.394324ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:29:03.08618Z","caller":"traceutil/trace.go:171","msg":"trace[1604427665] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"450.302386ms","start":"2026-04-21T01:29:02.635843Z","end":"2026-04-21T01:29:03.086146Z","steps":["trace[1604427665] 'read index received' (duration: 450.100252ms)","trace[1604427665] 'applied index is now lower than readState.Index' (duration: 201.294µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:03.086291Z","caller":"traceutil/trace.go:171","msg":"trace[1187974320] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"525.421484ms","start":"2026-04-21T01:29:02.560856Z","end":"2026-04-21T01:29:03.086278Z","steps":["trace[1187974320] 'process raft request' (duration: 525.159198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:03.086403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:02.56083Z","time spent":"525.486785ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:03.086531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.476498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T01:29:03.086609Z","caller":"traceutil/trace.go:171","msg":"trace[1621571926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"149.603472ms","start":"2026-04-21T01:29:02.936988Z","end":"2026-04-21T01:29:03.086591Z","steps":["trace[1621571926] 'agreement among raft nodes before linearized reading' (duration: 149.379256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:03.086864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.161023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-21T01:29:03.086974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.118553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T01:29:03.086936Z","caller":"traceutil/trace.go:171","msg":"trace[380909973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1082; }","duration":"128.274756ms","start":"2026-04-21T01:29:02.958645Z","end":"2026-04-21T01:29:03.086919Z","steps":["trace[380909973] 'agreement among raft nodes before linearized reading' (duration: 127.918579ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:03.087224Z","caller":"traceutil/trace.go:171","msg":"trace[922492833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"129.44978ms","start":"2026-04-21T01:29:02.957755Z","end":"2026-04-21T01:29:03.087205Z","steps":["trace[922492833] 'agreement among raft nodes before linearized reading' (duration: 128.96428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:03.087013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.169316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T01:29:03.087708Z","caller":"traceutil/trace.go:171","msg":"trace[2133110565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1082; }","duration":"451.90199ms","start":"2026-04-21T01:29:02.635791Z","end":"2026-04-21T01:29:03.087693Z","steps":["trace[2133110565] 'agreement among raft nodes before linearized reading' (duration: 450.850468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:03.087829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:02.635768Z","time spent":"452.034123ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T01:29:03.627186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.928725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:29:03.627326Z","caller":"traceutil/trace.go:171","msg":"trace[1846753579] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"536.173674ms","start":"2026-04-21T01:29:03.091138Z","end":"2026-04-21T01:29:03.627311Z","steps":["trace[1846753579] 'process raft request' (duration: 267.048165ms)","trace[1846753579] 'compare' (duration: 268.654828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:29:03.627386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:03.091122Z","time spent":"536.237275ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:29:03.627856Z","caller":"traceutil/trace.go:171","msg":"trace[1218105467] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"536.587743ms","start":"2026-04-21T01:29:03.09124Z","end":"2026-04-21T01:29:03.627828Z","steps":["trace[1218105467] 'process raft request' (duration: 536.034251ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:03.628105Z","caller":"traceutil/trace.go:171","msg":"trace[1188365229] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1121; }","duration":"379.802187ms","start":"2026-04-21T01:29:03.248281Z","end":"2026-04-21T01:29:03.628083Z","steps":["trace[1188365229] 'read index received' (duration: 109.901052ms)","trace[1188365229] 'applied index is now lower than readState.Index' (duration: 269.899895ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:03.628226Z","caller":"traceutil/trace.go:171","msg":"trace[534566104] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"536.03097ms","start":"2026-04-21T01:29:03.092178Z","end":"2026-04-21T01:29:03.628209Z","steps":["trace[534566104] 'process raft request' (duration: 535.627151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:03.628317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:03.092157Z","time spent":"536.117582ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:29:03.628436Z","caller":"traceutil/trace.go:171","msg":"trace[2009658926] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"536.015731ms","start":"2026-04-21T01:29:03.092387Z","end":"2026-04-21T01:29:03.628403Z","steps":["trace[2009658926] 'process raft request' (duration: 535.629742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:03.628537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:03.092376Z","time spent":"536.109422ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:03.628665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:03.091233Z","time spent":"537.176516ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:03.629176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.97411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:03.629902Z","caller":"traceutil/trace.go:171","msg":"trace[1830479438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"381.655727ms","start":"2026-04-21T01:29:03.248226Z","end":"2026-04-21T01:29:03.629882Z","steps":["trace[1830479438] 'agreement among raft nodes before linearized reading' (duration: 379.991801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:03.630062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:03.2482Z","time spent":"381.845712ms","remote":"127.0.0.1:42168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:29:03.890518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.243441ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-21T01:29:03.890633Z","caller":"traceutil/trace.go:171","msg":"trace[232660186] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"259.471654ms","start":"2026-04-21T01:29:03.631128Z","end":"2026-04-21T01:29:03.8906Z","steps":["trace[232660186] 'read index received' (duration: 119.149371ms)","trace[232660186] 'applied index is now lower than readState.Index' (duration: 140.320893ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:29:03.890716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.573866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:03.890743Z","caller":"traceutil/trace.go:171","msg":"trace[1512540755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"259.636018ms","start":"2026-04-21T01:29:03.631098Z","end":"2026-04-21T01:29:03.890734Z","steps":["trace[1512540755] 'agreement among raft nodes before linearized reading' (duration: 259.566756ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:03.918689Z","caller":"traceutil/trace.go:171","msg":"trace[706525535] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"225.235281ms","start":"2026-04-21T01:29:03.693437Z","end":"2026-04-21T01:29:03.918672Z","steps":["trace[706525535] 'process raft request' (duration: 225.093908ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:03.938395Z","caller":"traceutil/trace.go:171","msg":"trace[896521526] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"156.379147ms","start":"2026-04-21T01:29:03.781977Z","end":"2026-04-21T01:29:03.938356Z","steps":["trace[896521526] 'process raft request' (duration: 156.183223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:16.747645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626005878832631,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T01:29:16.768045Z","caller":"traceutil/trace.go:171","msg":"trace[1635824732] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"520.879582ms","start":"2026-04-21T01:29:16.247147Z","end":"2026-04-21T01:29:16.768027Z","steps":["trace[1635824732] 'read index received' (duration: 520.75761ms)","trace[1635824732] 'applied index is now lower than readState.Index' (duration: 121.222µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:29:16.768172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.001134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:16.768165Z","caller":"traceutil/trace.go:171","msg":"trace[1234451236] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"813.810855ms","start":"2026-04-21T01:29:15.954309Z","end":"2026-04-21T01:29:16.76812Z","steps":["trace[1234451236] 'process raft request' (duration: 813.605211ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:16.768199Z","caller":"traceutil/trace.go:171","msg":"trace[1927882748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1178; }","duration":"521.081086ms","start":"2026-04-21T01:29:16.247111Z","end":"2026-04-21T01:29:16.768192Z","steps":["trace[1927882748] 'agreement among raft nodes before linearized reading' (duration: 520.988924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:16.768222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:16.247095Z","time spent":"521.122837ms","remote":"127.0.0.1:42168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:29:16.768327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:15.954286Z","time spent":"813.971137ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:17.43483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.918788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:17.434889Z","caller":"traceutil/trace.go:171","msg":"trace[615379189] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1178; }","duration":"235.03054ms","start":"2026-04-21T01:29:17.199845Z","end":"2026-04-21T01:29:17.434875Z","steps":["trace[615379189] 'count revisions from in-memory index tree' (duration: 234.846165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:17.435174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.834662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-21T01:29:17.435291Z","caller":"traceutil/trace.go:171","msg":"trace[1216127753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"282.045306ms","start":"2026-04-21T01:29:17.153219Z","end":"2026-04-21T01:29:17.435265Z","steps":["trace[1216127753] 'range keys from in-memory index tree' (duration: 281.503606ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:32.134043Z","caller":"traceutil/trace.go:171","msg":"trace[1185697400] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"241.930168ms","start":"2026-04-21T01:29:31.892091Z","end":"2026-04-21T01:29:32.134021Z","steps":["trace[1185697400] 'read index received' (duration: 241.777525ms)","trace[1185697400] 'applied index is now lower than readState.Index' (duration: 151.633µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:32.134115Z","caller":"traceutil/trace.go:171","msg":"trace[1370642248] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"297.941673ms","start":"2026-04-21T01:29:31.836143Z","end":"2026-04-21T01:29:32.134085Z","steps":["trace[1370642248] 'process raft request' (duration: 297.743279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:32.1343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.955918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T01:29:32.134333Z","caller":"traceutil/trace.go:171","msg":"trace[1330232755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"242.05429ms","start":"2026-04-21T01:29:31.892269Z","end":"2026-04-21T01:29:32.134323Z","steps":["trace[1330232755] 'agreement among raft nodes before linearized reading' (duration: 241.883866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:32.134364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.247544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T01:29:32.134455Z","caller":"traceutil/trace.go:171","msg":"trace[1610374284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"242.414607ms","start":"2026-04-21T01:29:31.892019Z","end":"2026-04-21T01:29:32.134433Z","steps":["trace[1610374284] 'agreement among raft nodes before linearized reading' (duration: 242.129091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:32.134523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.00498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-04-21T01:29:32.134591Z","caller":"traceutil/trace.go:171","msg":"trace[1093566732] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1241; }","duration":"232.107132ms","start":"2026-04-21T01:29:31.902471Z","end":"2026-04-21T01:29:32.134578Z","steps":["trace[1093566732] 'agreement among raft nodes before linearized reading' (duration: 231.962089ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:32.273171Z","caller":"traceutil/trace.go:171","msg":"trace[1486106533] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"133.60373ms","start":"2026-04-21T01:29:32.139535Z","end":"2026-04-21T01:29:32.273139Z","steps":["trace[1486106533] 'process raft request' (duration: 106.778536ms)","trace[1486106533] 'compare' (duration: 26.685321ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:32.423685Z","caller":"traceutil/trace.go:171","msg":"trace[1076335509] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1289; }","duration":"175.19943ms","start":"2026-04-21T01:29:32.24846Z","end":"2026-04-21T01:29:32.42366Z","steps":["trace[1076335509] 'read index received' (duration: 139.142111ms)","trace[1076335509] 'applied index is now lower than readState.Index' (duration: 36.056449ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:32.424011Z","caller":"traceutil/trace.go:171","msg":"trace[1764052476] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"283.101557ms","start":"2026-04-21T01:29:32.140894Z","end":"2026-04-21T01:29:32.423996Z","steps":["trace[1764052476] 'process raft request' (duration: 246.667991ms)","trace[1764052476] 'compare' (duration: 35.549288ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:32.424381Z","caller":"traceutil/trace.go:171","msg":"trace[1514331516] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"283.287411ms","start":"2026-04-21T01:29:32.141075Z","end":"2026-04-21T01:29:32.424362Z","steps":["trace[1514331516] 'process raft request' (duration: 282.470235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:32.424721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.7401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-rfqhd\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-04-21T01:29:32.424801Z","caller":"traceutil/trace.go:171","msg":"trace[158582909] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-rfqhd; range_end:; response_count:1; response_revision:1245; }","duration":"165.843302ms","start":"2026-04-21T01:29:32.258936Z","end":"2026-04-21T01:29:32.424779Z","steps":["trace[158582909] 'agreement among raft nodes before linearized reading' (duration: 165.634738ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:32.425223Z","caller":"traceutil/trace.go:171","msg":"trace[2056878180] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"281.652178ms","start":"2026-04-21T01:29:32.143559Z","end":"2026-04-21T01:29:32.425211Z","steps":["trace[2056878180] 'process raft request' (duration: 280.037766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:32.425907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.431093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:32.42597Z","caller":"traceutil/trace.go:171","msg":"trace[770592499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"177.693728ms","start":"2026-04-21T01:29:32.248252Z","end":"2026-04-21T01:29:32.425946Z","steps":["trace[770592499] 'agreement among raft nodes before linearized reading' (duration: 175.895083ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:32.542922Z","caller":"traceutil/trace.go:171","msg":"trace[1341083749] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"106.385528ms","start":"2026-04-21T01:29:32.436507Z","end":"2026-04-21T01:29:32.542893Z","steps":["trace[1341083749] 'process raft request' (duration: 92.728756ms)","trace[1341083749] 'compare' (duration: 13.52386ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:32.947988Z","caller":"traceutil/trace.go:171","msg":"trace[1578551217] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"395.014385ms","start":"2026-04-21T01:29:32.552957Z","end":"2026-04-21T01:29:32.947972Z","steps":["trace[1578551217] 'process raft request' (duration: 394.955894ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:32.948022Z","caller":"traceutil/trace.go:171","msg":"trace[604594281] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"399.154658ms","start":"2026-04-21T01:29:32.54884Z","end":"2026-04-21T01:29:32.947994Z","steps":["trace[604594281] 'process raft request' (duration: 398.660198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:32.948104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:32.552943Z","time spent":"395.098988ms","remote":"127.0.0.1:42576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:32.948178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:32.548831Z","time spent":"399.25264ms","remote":"127.0.0.1:42396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:33.951478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.078408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:29:33.951678Z","caller":"traceutil/trace.go:171","msg":"trace[2054826434] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"1.032428878s","start":"2026-04-21T01:29:32.91922Z","end":"2026-04-21T01:29:33.951649Z","steps":["trace[2054826434] 'process raft request' (duration: 639.093365ms)","trace[2054826434] 'compare' (duration: 392.766791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:29:33.951826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:32.919198Z","time spent":"1.03254655s","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:34.086043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.451067ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-21T01:29:34.086164Z","caller":"traceutil/trace.go:171","msg":"trace[41273767] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1297; }","duration":"507.186029ms","start":"2026-04-21T01:29:33.578956Z","end":"2026-04-21T01:29:34.086142Z","steps":["trace[41273767] 'read index received' (duration: 315.085754ms)","trace[41273767] 'applied index is now lower than readState.Index' (duration: 192.098715ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:29:34.086378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.404643ms","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-04-21T01:29:34.086431Z","caller":"traceutil/trace.go:171","msg":"trace[95248696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"507.494555ms","start":"2026-04-21T01:29:33.578924Z","end":"2026-04-21T01:29:34.086419Z","steps":["trace[95248696] 'agreement among raft nodes before linearized reading' (duration: 507.274061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:34.086474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:33.578909Z","time spent":"507.554726ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-21T01:29:34.250942Z","caller":"traceutil/trace.go:171","msg":"trace[1470268881] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"159.69804ms","start":"2026-04-21T01:29:34.091223Z","end":"2026-04-21T01:29:34.250921Z","steps":["trace[1470268881] 'process raft request' (duration: 159.542317ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:34.282198Z","caller":"traceutil/trace.go:171","msg":"trace[1040956525] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1299; }","duration":"143.550679ms","start":"2026-04-21T01:29:34.138627Z","end":"2026-04-21T01:29:34.282178Z","steps":["trace[1040956525] 'read index received' (duration: 112.171725ms)","trace[1040956525] 'applied index is now lower than readState.Index' (duration: 31.378014ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:34.282249Z","caller":"traceutil/trace.go:171","msg":"trace[1592284176] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"154.725761ms","start":"2026-04-21T01:29:34.127492Z","end":"2026-04-21T01:29:34.282218Z","steps":["trace[1592284176] 'process raft request' (duration: 154.567288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:34.282395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.755583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T01:29:34.282424Z","caller":"traceutil/trace.go:171","msg":"trace[1303948735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1253; }","duration":"143.830114ms","start":"2026-04-21T01:29:34.138587Z","end":"2026-04-21T01:29:34.282417Z","steps":["trace[1303948735] 'agreement among raft nodes before linearized reading' (duration: 143.706262ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:34.393781Z","caller":"traceutil/trace.go:171","msg":"trace[1984877252] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"108.652073ms","start":"2026-04-21T01:29:34.2851Z","end":"2026-04-21T01:29:34.393752Z","steps":["trace[1984877252] 'process raft request' (duration: 86.143165ms)","trace[1984877252] 'compare' (duration: 22.243822ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:34.411452Z","caller":"traceutil/trace.go:171","msg":"trace[1017008962] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"125.445207ms","start":"2026-04-21T01:29:34.28598Z","end":"2026-04-21T01:29:34.411425Z","steps":["trace[1017008962] 'process raft request' (duration: 125.308425ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:36.115Z","caller":"traceutil/trace.go:171","msg":"trace[1982142943] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"153.232521ms","start":"2026-04-21T01:29:35.961741Z","end":"2026-04-21T01:29:36.114973Z","steps":["trace[1982142943] 'process raft request' (duration: 153.049967ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:36.356313Z","caller":"traceutil/trace.go:171","msg":"trace[1942976164] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1307; }","duration":"109.095543ms","start":"2026-04-21T01:29:36.247182Z","end":"2026-04-21T01:29:36.356277Z","steps":["trace[1942976164] 'read index received' (duration: 21.475519ms)","trace[1942976164] 'applied index is now lower than readState.Index' (duration: 87.618514ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:36.356489Z","caller":"traceutil/trace.go:171","msg":"trace[1066687220] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"143.933594ms","start":"2026-04-21T01:29:36.212542Z","end":"2026-04-21T01:29:36.356476Z","steps":["trace[1066687220] 'process raft request' (duration: 56.110366ms)","trace[1066687220] 'compare' (duration: 87.444151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:29:36.356796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.289867ms","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-04-21T01:29:36.356855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.650644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:36.356967Z","caller":"traceutil/trace.go:171","msg":"trace[1552653442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"109.826387ms","start":"2026-04-21T01:29:36.247119Z","end":"2026-04-21T01:29:36.356945Z","steps":["trace[1552653442] 'agreement among raft nodes before linearized reading' (duration: 109.655044ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:36.356969Z","caller":"traceutil/trace.go:171","msg":"trace[1603739924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1260; }","duration":"102.48944ms","start":"2026-04-21T01:29:36.254431Z","end":"2026-04-21T01:29:36.35692Z","steps":["trace[1603739924] 'agreement among raft nodes before linearized reading' (duration: 102.160274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:36.81389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.564522ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:36.814023Z","caller":"traceutil/trace.go:171","msg":"trace[335715024] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1262; }","duration":"217.719915ms","start":"2026-04-21T01:29:36.596283Z","end":"2026-04-21T01:29:36.814003Z","steps":["trace[335715024] 'range keys from in-memory index tree' (duration: 217.547642ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:36.814178Z","caller":"traceutil/trace.go:171","msg":"trace[210331293] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"402.906922ms","start":"2026-04-21T01:29:36.411226Z","end":"2026-04-21T01:29:36.814133Z","steps":["trace[210331293] 'process raft request' (duration: 322.42318ms)","trace[210331293] 'compare' (duration: 80.183406ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:36.814287Z","caller":"traceutil/trace.go:171","msg":"trace[1150972441] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"398.629107ms","start":"2026-04-21T01:29:36.415641Z","end":"2026-04-21T01:29:36.81427Z","steps":["trace[1150972441] 'process raft request' (duration: 398.444744ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:36.814338Z","caller":"traceutil/trace.go:171","msg":"trace[603343441] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"399.017365ms","start":"2026-04-21T01:29:36.415296Z","end":"2026-04-21T01:29:36.814313Z","steps":["trace[603343441] 'process raft request' (duration: 398.72309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:36.814388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:36.415625Z","time spent":"398.703149ms","remote":"127.0.0.1:42228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T01:29:36.814406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.819861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T01:29:36.814454Z","caller":"traceutil/trace.go:171","msg":"trace[933205895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1266; }","duration":"398.916454ms","start":"2026-04-21T01:29:36.415526Z","end":"2026-04-21T01:29:36.814442Z","steps":["trace[933205895] 'agreement among raft nodes before linearized reading' (duration: 398.723359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:36.814484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:36.41527Z","time spent":"399.126619ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:29:36.814542Z","caller":"traceutil/trace.go:171","msg":"trace[1308972744] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"398.878222ms","start":"2026-04-21T01:29:36.415653Z","end":"2026-04-21T01:29:36.814531Z","steps":["trace[1308972744] 'process raft request' (duration: 398.477914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:36.814613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:36.415643Z","time spent":"398.927523ms","remote":"127.0.0.1:42228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T01:29:36.814605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:36.411185Z","time spent":"403.125016ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:36.814504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:36.415514Z","time spent":"398.981785ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T01:29:36.814194Z","caller":"traceutil/trace.go:171","msg":"trace[339732747] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1310; }","duration":"398.606446ms","start":"2026-04-21T01:29:36.41556Z","end":"2026-04-21T01:29:36.814166Z","steps":["trace[339732747] 'read index received' (duration: 318.041643ms)","trace[339732747] 'applied index is now lower than readState.Index' (duration: 80.563593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:29:36.815158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.56465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T01:29:36.815235Z","caller":"traceutil/trace.go:171","msg":"trace[1458302572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"363.759574ms","start":"2026-04-21T01:29:36.451461Z","end":"2026-04-21T01:29:36.81522Z","steps":["trace[1458302572] 'agreement among raft nodes before linearized reading' (duration: 363.54098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:36.81535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:36.451437Z","time spent":"363.895226ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T01:29:36.815713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.663804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T01:29:36.815796Z","caller":"traceutil/trace.go:171","msg":"trace[751871661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"333.800747ms","start":"2026-04-21T01:29:36.481976Z","end":"2026-04-21T01:29:36.815777Z","steps":["trace[751871661] 'agreement among raft nodes before linearized reading' (duration: 333.547572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:36.815842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:36.481951Z","time spent":"333.883058ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T01:29:38.46729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.537747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:29:38.467413Z","caller":"traceutil/trace.go:171","msg":"trace[1241115022] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"346.864837ms","start":"2026-04-21T01:29:38.120529Z","end":"2026-04-21T01:29:38.467393Z","steps":["trace[1241115022] 'process raft request' (duration: 193.908322ms)","trace[1241115022] 'compare' (duration: 152.232791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:29:38.467474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:38.120501Z","time spent":"346.944709ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:29:38.490522Z","caller":"traceutil/trace.go:171","msg":"trace[1332099040] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1319; }","duration":"245.677073ms","start":"2026-04-21T01:29:38.244825Z","end":"2026-04-21T01:29:38.490502Z","steps":["trace[1332099040] 'read index received' (duration: 69.643417ms)","trace[1332099040] 'applied index is now lower than readState.Index' (duration: 176.033136ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:38.490598Z","caller":"traceutil/trace.go:171","msg":"trace[711715309] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"318.801099ms","start":"2026-04-21T01:29:38.171791Z","end":"2026-04-21T01:29:38.490592Z","steps":["trace[711715309] 'process raft request' (duration: 318.603115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:38.49068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:38.17177Z","time spent":"318.846129ms","remote":"127.0.0.1:42196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:38.490914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.096121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:38.490939Z","caller":"traceutil/trace.go:171","msg":"trace[1390337957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"246.153762ms","start":"2026-04-21T01:29:38.24478Z","end":"2026-04-21T01:29:38.490933Z","steps":["trace[1390337957] 'agreement among raft nodes before linearized reading' (duration: 246.090261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:38.491071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.853188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-21T01:29:38.491094Z","caller":"traceutil/trace.go:171","msg":"trace[189400911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"108.915269ms","start":"2026-04-21T01:29:38.382174Z","end":"2026-04-21T01:29:38.49109Z","steps":["trace[189400911] 'agreement among raft nodes before linearized reading' (duration: 108.841408ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:42.20609Z","caller":"traceutil/trace.go:171","msg":"trace[642963911] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"276.185979ms","start":"2026-04-21T01:29:41.929884Z","end":"2026-04-21T01:29:42.20607Z","steps":["trace[642963911] 'read index received' (duration: 276.003606ms)","trace[642963911] 'applied index is now lower than readState.Index' (duration: 181.453µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:42.206141Z","caller":"traceutil/trace.go:171","msg":"trace[192757617] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"379.706031ms","start":"2026-04-21T01:29:41.826404Z","end":"2026-04-21T01:29:42.20611Z","steps":["trace[192757617] 'process raft request' (duration: 379.498057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:42.206192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.286861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:42.206215Z","caller":"traceutil/trace.go:171","msg":"trace[414185143] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1299; }","duration":"276.375593ms","start":"2026-04-21T01:29:41.929832Z","end":"2026-04-21T01:29:42.206207Z","steps":["trace[414185143] 'agreement among raft nodes before linearized reading' (duration: 276.309022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:42.206338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:41.826386Z","time spent":"379.817713ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:49.104265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626005878832971,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T01:29:49.259102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010648244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:29:49.259375Z","caller":"traceutil/trace.go:171","msg":"trace[18518005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"1.010954141s","start":"2026-04-21T01:29:48.248402Z","end":"2026-04-21T01:29:49.259356Z","steps":["trace[18518005] 'range keys from in-memory index tree' (duration: 1.010484711s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:49.259508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:48.248384Z","time spent":"1.011111643s","remote":"127.0.0.1:42168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T01:29:49.259594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205721248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T01:29:49.259706Z","caller":"traceutil/trace.go:171","msg":"trace[973709329] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"655.584244ms","start":"2026-04-21T01:29:48.604074Z","end":"2026-04-21T01:29:49.259658Z","steps":["trace[973709329] 'read index received' (duration: 643.907431ms)","trace[973709329] 'applied index is now lower than readState.Index' (duration: 11.675943ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:29:49.259761Z","caller":"traceutil/trace.go:171","msg":"trace[1044771845] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:1344; }","duration":"1.205898192s","start":"2026-04-21T01:29:48.053778Z","end":"2026-04-21T01:29:49.259676Z","steps":["trace[1044771845] 'range keys from in-memory index tree' (duration: 1.205456714s)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:29:49.259805Z","caller":"traceutil/trace.go:171","msg":"trace[1515129708] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"664.249746ms","start":"2026-04-21T01:29:48.59554Z","end":"2026-04-21T01:29:49.25979Z","steps":["trace[1515129708] 'process raft request' (duration: 652.39276ms)","trace[1515129708] 'compare' (duration: 10.921158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:29:49.259844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:48.053751Z","time spent":"1.206080026s","remote":"127.0.0.1:42196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-04-21T01:29:49.259914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:48.595512Z","time spent":"664.326358ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:29:49.26001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.297263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T01:29:49.260076Z","caller":"traceutil/trace.go:171","msg":"trace[1499529545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1345; }","duration":"251.400725ms","start":"2026-04-21T01:29:49.008659Z","end":"2026-04-21T01:29:49.26006Z","steps":["trace[1499529545] 'agreement among raft nodes before linearized reading' (duration: 251.252632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:49.260265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.793447ms","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-04-21T01:29:49.260309Z","caller":"traceutil/trace.go:171","msg":"trace[672057505] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1345; }","duration":"222.878159ms","start":"2026-04-21T01:29:49.03742Z","end":"2026-04-21T01:29:49.260298Z","steps":["trace[672057505] 'agreement among raft nodes before linearized reading' (duration: 222.763107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:49.260412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.914029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T01:29:49.260535Z","caller":"traceutil/trace.go:171","msg":"trace[1125761416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"222.054582ms","start":"2026-04-21T01:29:49.03842Z","end":"2026-04-21T01:29:49.260475Z","steps":["trace[1125761416] 'agreement among raft nodes before linearized reading' (duration: 221.754146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:49.260566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.893709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T01:29:49.260607Z","caller":"traceutil/trace.go:171","msg":"trace[162507159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1345; }","duration":"228.97392ms","start":"2026-04-21T01:29:49.031621Z","end":"2026-04-21T01:29:49.260595Z","steps":["trace[162507159] 'agreement among raft nodes before linearized reading' (duration: 228.858937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:49.260618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.538554ms","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-04-21T01:29:49.260658Z","caller":"traceutil/trace.go:171","msg":"trace[1081195173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1345; }","duration":"656.606265ms","start":"2026-04-21T01:29:48.604041Z","end":"2026-04-21T01:29:49.260647Z","steps":["trace[1081195173] 'agreement among raft nodes before linearized reading' (duration: 656.475572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:29:49.260696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:29:48.604022Z","time spent":"656.666945ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T01:29:49.260761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.014761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T01:29:49.262098Z","caller":"traceutil/trace.go:171","msg":"trace[1771169768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1345; }","duration":"223.366718ms","start":"2026-04-21T01:29:49.038715Z","end":"2026-04-21T01:29:49.262081Z","steps":["trace[1771169768] 'agreement among raft nodes before linearized reading' (duration: 221.802937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:04.988542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.481342ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-21T01:30:05.321893Z","caller":"traceutil/trace.go:171","msg":"trace[314385771] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1471; }","duration":"352.302986ms","start":"2026-04-21T01:30:04.969569Z","end":"2026-04-21T01:30:05.321872Z","steps":["trace[314385771] 'read index received' (duration: 352.187914ms)","trace[314385771] 'applied index is now lower than readState.Index' (duration: 113.982µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:05.322001Z","caller":"traceutil/trace.go:171","msg":"trace[993321978] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"391.589058ms","start":"2026-04-21T01:30:04.930368Z","end":"2026-04-21T01:30:05.321957Z","steps":["trace[993321978] 'process raft request' (duration: 391.368733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:05.32213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:04.930341Z","time spent":"391.71539ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:05.32213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.52563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:30:05.322215Z","caller":"traceutil/trace.go:171","msg":"trace[554203097] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1418; }","duration":"352.736483ms","start":"2026-04-21T01:30:04.969466Z","end":"2026-04-21T01:30:05.322203Z","steps":["trace[554203097] 'agreement among raft nodes before linearized reading' (duration: 352.57242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:05.322284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:04.969437Z","time spent":"352.833416ms","remote":"127.0.0.1:42294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-04-21T01:30:05.550336Z","caller":"traceutil/trace.go:171","msg":"trace[399235783] linearizableReadLoop","detail":"{readStateIndex:1473; appliedIndex:1472; }","duration":"228.360117ms","start":"2026-04-21T01:30:05.321946Z","end":"2026-04-21T01:30:05.550306Z","steps":["trace[399235783] 'read index received' (duration: 179.712768ms)","trace[399235783] 'applied index is now lower than readState.Index' (duration: 48.645579ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:05.550402Z","caller":"traceutil/trace.go:171","msg":"trace[2027561455] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"238.975958ms","start":"2026-04-21T01:30:05.3114Z","end":"2026-04-21T01:30:05.550376Z","steps":["trace[2027561455] 'process raft request' (duration: 190.250587ms)","trace[2027561455] 'compare' (duration: 48.462516ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:05.550585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.267599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T01:30:05.550653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.392147ms","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":"warn","ts":"2026-04-21T01:30:05.550738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.034423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T01:30:05.550782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.035845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-21T01:30:05.550794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.050026ms","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-04-21T01:30:05.550817Z","caller":"traceutil/trace.go:171","msg":"trace[1626855458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1419; }","duration":"190.141137ms","start":"2026-04-21T01:30:05.36066Z","end":"2026-04-21T01:30:05.550801Z","steps":["trace[1626855458] 'agreement among raft nodes before linearized reading' (duration: 189.948042ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:05.550774Z","caller":"traceutil/trace.go:171","msg":"trace[862711058] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1419; }","duration":"565.548221ms","start":"2026-04-21T01:30:04.985208Z","end":"2026-04-21T01:30:05.550756Z","steps":["trace[862711058] 'agreement among raft nodes before linearized reading' (duration: 565.259334ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:05.550832Z","caller":"traceutil/trace.go:171","msg":"trace[935129113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1419; }","duration":"188.108586ms","start":"2026-04-21T01:30:05.362709Z","end":"2026-04-21T01:30:05.550818Z","steps":["trace[935129113] 'agreement among raft nodes before linearized reading' (duration: 187.976033ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:05.550841Z","caller":"traceutil/trace.go:171","msg":"trace[2048624972] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1419; }","duration":"185.107706ms","start":"2026-04-21T01:30:05.365721Z","end":"2026-04-21T01:30:05.550829Z","steps":["trace[2048624972] 'agreement among raft nodes before linearized reading' (duration: 185.003603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:05.550892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.252908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-21T01:30:05.550882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:04.985168Z","time spent":"565.696834ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-21T01:30:05.550695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.740319ms","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-04-21T01:30:05.551091Z","caller":"traceutil/trace.go:171","msg":"trace[1867491885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1419; }","duration":"187.135057ms","start":"2026-04-21T01:30:05.363922Z","end":"2026-04-21T01:30:05.551057Z","steps":["trace[1867491885] 'agreement among raft nodes before linearized reading' (duration: 186.670857ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:05.550693Z","caller":"traceutil/trace.go:171","msg":"trace[1013518235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1419; }","duration":"302.444142ms","start":"2026-04-21T01:30:05.248223Z","end":"2026-04-21T01:30:05.550667Z","steps":["trace[1013518235] 'agreement among raft nodes before linearized reading' (duration: 302.193337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:05.551219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.248203Z","time spent":"302.983483ms","remote":"127.0.0.1:42168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T01:30:05.550944Z","caller":"traceutil/trace.go:171","msg":"trace[1987128123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1419; }","duration":"190.3367ms","start":"2026-04-21T01:30:05.360597Z","end":"2026-04-21T01:30:05.550934Z","steps":["trace[1987128123] 'agreement among raft nodes before linearized reading' (duration: 190.144217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:05.551332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.869641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T01:30:05.55142Z","caller":"traceutil/trace.go:171","msg":"trace[1697400316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1419; }","duration":"191.982382ms","start":"2026-04-21T01:30:05.359418Z","end":"2026-04-21T01:30:05.551401Z","steps":["trace[1697400316] 'agreement among raft nodes before linearized reading' (duration: 191.166946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.15899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.478086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:30:06.159126Z","caller":"traceutil/trace.go:171","msg":"trace[788599255] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"603.454156ms","start":"2026-04-21T01:30:05.555647Z","end":"2026-04-21T01:30:06.159101Z","steps":["trace[788599255] 'process raft request' (duration: 105.761846ms)","trace[788599255] 'compare' (duration: 497.307913ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:06.159211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.555624Z","time spent":"603.543817ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:30:06.228477Z","caller":"traceutil/trace.go:171","msg":"trace[505536119] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"671.925599ms","start":"2026-04-21T01:30:05.556523Z","end":"2026-04-21T01:30:06.228449Z","steps":["trace[505536119] 'process raft request' (duration: 671.680934ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:06.228542Z","caller":"traceutil/trace.go:171","msg":"trace[392238363] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"671.96511ms","start":"2026-04-21T01:30:05.556521Z","end":"2026-04-21T01:30:06.228486Z","steps":["trace[392238363] 'process raft request' (duration: 671.808526ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:06.228614Z","caller":"traceutil/trace.go:171","msg":"trace[1488406365] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"671.94476ms","start":"2026-04-21T01:30:05.556633Z","end":"2026-04-21T01:30:06.228578Z","steps":["trace[1488406365] 'process raft request' (duration: 671.749025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.228643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.556509Z","time spent":"672.059672ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:30:06.228672Z","caller":"traceutil/trace.go:171","msg":"trace[749894333] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"671.778496ms","start":"2026-04-21T01:30:05.556769Z","end":"2026-04-21T01:30:06.228548Z","steps":["trace[749894333] 'process raft request' (duration: 671.656904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.228838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.556586Z","time spent":"672.111123ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:30:06.228905Z","caller":"traceutil/trace.go:171","msg":"trace[235866317] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"670.374288ms","start":"2026-04-21T01:30:05.558486Z","end":"2026-04-21T01:30:06.228861Z","steps":["trace[235866317] 'process raft request' (duration: 669.991151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.228727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.556502Z","time spent":"672.107423ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:06.228988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.558472Z","time spent":"670.471971ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T01:30:06.229056Z","caller":"traceutil/trace.go:171","msg":"trace[1107478639] linearizableReadLoop","detail":"{readStateIndex:1480; appliedIndex:1474; }","duration":"356.445219ms","start":"2026-04-21T01:30:05.872586Z","end":"2026-04-21T01:30:06.229031Z","steps":["trace[1107478639] 'read index received' (duration: 355.562701ms)","trace[1107478639] 'applied index is now lower than readState.Index' (duration: 881.188µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:06.229098Z","caller":"traceutil/trace.go:171","msg":"trace[1569306326] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"669.612573ms","start":"2026-04-21T01:30:05.559439Z","end":"2026-04-21T01:30:06.229052Z","steps":["trace[1569306326] 'process raft request' (duration: 669.299417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.229187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.607491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T01:30:06.229229Z","caller":"traceutil/trace.go:171","msg":"trace[227173185] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1426; }","duration":"356.699593ms","start":"2026-04-21T01:30:05.872518Z","end":"2026-04-21T01:30:06.229217Z","steps":["trace[227173185] 'agreement among raft nodes before linearized reading' (duration: 356.604331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.229243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.900217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-21T01:30:06.229264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.872495Z","time spent":"356.758144ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-04-21T01:30:06.229286Z","caller":"traceutil/trace.go:171","msg":"trace[1980651606] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1426; }","duration":"348.011949ms","start":"2026-04-21T01:30:05.881264Z","end":"2026-04-21T01:30:06.229276Z","steps":["trace[1980651606] 'agreement among raft nodes before linearized reading' (duration: 347.909627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:06.229327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.881239Z","time spent":"348.077661ms","remote":"127.0.0.1:42378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-21T01:30:06.22935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.559422Z","time spent":"669.798706ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:06.229446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:05.556747Z","time spent":"672.086603ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:06.449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.868124ms","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-04-21T01:30:06.449148Z","caller":"traceutil/trace.go:171","msg":"trace[1732608311] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1426; }","duration":"157.040987ms","start":"2026-04-21T01:30:06.292049Z","end":"2026-04-21T01:30:06.44909Z","steps":["trace[1732608311] 'range keys from in-memory index tree' (duration: 156.628509ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:15.556807Z","caller":"traceutil/trace.go:171","msg":"trace[1051351732] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"171.825861ms","start":"2026-04-21T01:30:15.384953Z","end":"2026-04-21T01:30:15.556779Z","steps":["trace[1051351732] 'process raft request' (duration: 171.691789ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:16.170341Z","caller":"traceutil/trace.go:171","msg":"trace[1387745648] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"149.083439ms","start":"2026-04-21T01:30:16.021196Z","end":"2026-04-21T01:30:16.170279Z","steps":["trace[1387745648] 'process raft request' (duration: 148.943916ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:22.412909Z","caller":"traceutil/trace.go:171","msg":"trace[267654829] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"101.751457ms","start":"2026-04-21T01:30:22.311104Z","end":"2026-04-21T01:30:22.412856Z","steps":["trace[267654829] 'process raft request' (duration: 28.325908ms)","trace[267654829] 'compare' (duration: 73.260696ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:22.577108Z","caller":"traceutil/trace.go:171","msg":"trace[279065606] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"159.491674ms","start":"2026-04-21T01:30:22.417599Z","end":"2026-04-21T01:30:22.577091Z","steps":["trace[279065606] 'process raft request' (duration: 158.797642ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:22.577201Z","caller":"traceutil/trace.go:171","msg":"trace[2059971435] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"159.391391ms","start":"2026-04-21T01:30:22.417786Z","end":"2026-04-21T01:30:22.577177Z","steps":["trace[2059971435] 'process raft request' (duration: 159.064705ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:22.577455Z","caller":"traceutil/trace.go:171","msg":"trace[690874129] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"159.84237ms","start":"2026-04-21T01:30:22.417604Z","end":"2026-04-21T01:30:22.577447Z","steps":["trace[690874129] 'process raft request' (duration: 159.215379ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:25.796559Z","caller":"traceutil/trace.go:171","msg":"trace[1817451737] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"104.22922ms","start":"2026-04-21T01:30:25.692304Z","end":"2026-04-21T01:30:25.796533Z","steps":["trace[1817451737] 'process raft request' (duration: 104.051947ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:25.837825Z","caller":"traceutil/trace.go:171","msg":"trace[1929643466] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"137.00319ms","start":"2026-04-21T01:30:25.700798Z","end":"2026-04-21T01:30:25.837801Z","steps":["trace[1929643466] 'process raft request' (duration: 136.874188ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:32.773076Z","caller":"traceutil/trace.go:171","msg":"trace[1034323981] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"113.206042ms","start":"2026-04-21T01:30:32.659845Z","end":"2026-04-21T01:30:32.773051Z","steps":["trace[1034323981] 'process raft request' (duration: 113.144251ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:32.7731Z","caller":"traceutil/trace.go:171","msg":"trace[2118935837] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"113.756251ms","start":"2026-04-21T01:30:32.659292Z","end":"2026-04-21T01:30:32.773049Z","steps":["trace[2118935837] 'process raft request' (duration: 112.055541ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:36.691667Z","caller":"traceutil/trace.go:171","msg":"trace[1339161497] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"118.868774ms","start":"2026-04-21T01:30:36.572768Z","end":"2026-04-21T01:30:36.691637Z","steps":["trace[1339161497] 'process raft request' (duration: 111.498062ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:40.676864Z","caller":"traceutil/trace.go:171","msg":"trace[2042091400] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"107.809305ms","start":"2026-04-21T01:30:40.569022Z","end":"2026-04-21T01:30:40.676832Z","steps":["trace[2042091400] 'process raft request' (duration: 107.588502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:43.155537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.216751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:30:43.155688Z","caller":"traceutil/trace.go:171","msg":"trace[773135775] linearizableReadLoop","detail":"{readStateIndex:1655; appliedIndex:1652; }","duration":"106.206887ms","start":"2026-04-21T01:30:43.04947Z","end":"2026-04-21T01:30:43.155677Z","steps":["trace[773135775] 'read index received' (duration: 34.951µs)","trace[773135775] 'applied index is now lower than readState.Index' (duration: 106.171256ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:43.155783Z","caller":"traceutil/trace.go:171","msg":"trace[477982782] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"240.361235ms","start":"2026-04-21T01:30:42.915389Z","end":"2026-04-21T01:30:43.155751Z","steps":["trace[477982782] 'process raft request' (duration: 126.863748ms)","trace[477982782] 'compare' (duration: 113.13001ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:30:43.155882Z","caller":"traceutil/trace.go:171","msg":"trace[2108319605] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"240.07857ms","start":"2026-04-21T01:30:42.915728Z","end":"2026-04-21T01:30:43.155807Z","steps":["trace[2108319605] 'process raft request' (duration: 239.913497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:43.15595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.485952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-21T01:30:43.155978Z","caller":"traceutil/trace.go:171","msg":"trace[762593960] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1594; }","duration":"106.551362ms","start":"2026-04-21T01:30:43.049418Z","end":"2026-04-21T01:30:43.155969Z","steps":["trace[762593960] 'agreement among raft nodes before linearized reading' (duration: 106.292458ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:30:43.156684Z","caller":"traceutil/trace.go:171","msg":"trace[476727267] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"240.170132ms","start":"2026-04-21T01:30:42.915632Z","end":"2026-04-21T01:30:43.155802Z","steps":["trace[476727267] 'process raft request' (duration: 239.979259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:43.820248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.958726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T01:30:43.820397Z","caller":"traceutil/trace.go:171","msg":"trace[1099046395] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"660.439354ms","start":"2026-04-21T01:30:43.159936Z","end":"2026-04-21T01:30:43.820376Z","steps":["trace[1099046395] 'process raft request' (duration: 151.248374ms)","trace[1099046395] 'compare' (duration: 508.534809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:43.820463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.159919Z","time spent":"660.511726ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T01:30:44.174054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.68027ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-21T01:30:44.174163Z","caller":"traceutil/trace.go:171","msg":"trace[513008129] linearizableReadLoop","detail":"{readStateIndex:1657; appliedIndex:1655; }","duration":"926.792547ms","start":"2026-04-21T01:30:43.247351Z","end":"2026-04-21T01:30:44.174144Z","steps":["trace[513008129] 'read index received' (duration: 63.842736ms)","trace[513008129] 'applied index is now lower than readState.Index' (duration: 862.948131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:30:44.174567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.209284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:44.174605Z","caller":"traceutil/trace.go:171","msg":"trace[674937187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1595; }","duration":"927.293716ms","start":"2026-04-21T01:30:43.247301Z","end":"2026-04-21T01:30:44.174595Z","steps":["trace[674937187] 'agreement among raft nodes before linearized reading' (duration: 927.212754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:44.174638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.247279Z","time spent":"927.350896ms","remote":"127.0.0.1:42168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T01:30:44.230435Z","caller":"traceutil/trace.go:171","msg":"trace[2093739415] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"250.6633ms","start":"2026-04-21T01:30:43.979752Z","end":"2026-04-21T01:30:44.230416Z","steps":["trace[2093739415] 'process raft request' (duration: 250.384225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:44.230703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.89285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:30:44.230873Z","caller":"traceutil/trace.go:171","msg":"trace[63599040] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1596; }","duration":"750.117795ms","start":"2026-04-21T01:30:43.480735Z","end":"2026-04-21T01:30:44.230853Z","steps":["trace[63599040] 'agreement among raft nodes before linearized reading' (duration: 749.88708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:30:44.231043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T01:30:43.480718Z","time spent":"750.306408ms","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-04-21T01:31:29.420141Z","caller":"traceutil/trace.go:171","msg":"trace[1942676156] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"165.875065ms","start":"2026-04-21T01:31:29.254239Z","end":"2026-04-21T01:31:29.420114Z","steps":["trace[1942676156] 'process raft request' (duration: 146.15035ms)","trace[1942676156] 'compare' (duration: 19.52411ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:32:01.761262Z","caller":"traceutil/trace.go:171","msg":"trace[2032545136] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"158.810527ms","start":"2026-04-21T01:32:01.602429Z","end":"2026-04-21T01:32:01.761239Z","steps":["trace[2032545136] 'read index received' (duration: 158.602293ms)","trace[2032545136] 'applied index is now lower than readState.Index' (duration: 207.104µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:32:01.761292Z","caller":"traceutil/trace.go:171","msg":"trace[1232683715] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"189.901344ms","start":"2026-04-21T01:32:01.571362Z","end":"2026-04-21T01:32:01.761264Z","steps":["trace[1232683715] 'process raft request' (duration: 189.724941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:32:01.761569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.116982ms","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-04-21T01:32:01.761618Z","caller":"traceutil/trace.go:171","msg":"trace[504144878] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1947; }","duration":"159.201774ms","start":"2026-04-21T01:32:01.602402Z","end":"2026-04-21T01:32:01.761603Z","steps":["trace[504144878] 'agreement among raft nodes before linearized reading' (duration: 159.022751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:32:01.761697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.215875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-21T01:32:01.761738Z","caller":"traceutil/trace.go:171","msg":"trace[2057649518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1947; }","duration":"115.311688ms","start":"2026-04-21T01:32:01.646413Z","end":"2026-04-21T01:32:01.761724Z","steps":["trace[2057649518] 'agreement among raft nodes before linearized reading' (duration: 115.148334ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T01:33:30.397051Z","caller":"traceutil/trace.go:171","msg":"trace[687245599] linearizableReadLoop","detail":"{readStateIndex:2490; appliedIndex:2488; }","duration":"158.900208ms","start":"2026-04-21T01:33:30.238127Z","end":"2026-04-21T01:33:30.397027Z","steps":["trace[687245599] 'read index received' (duration: 24.464418ms)","trace[687245599] 'applied index is now lower than readState.Index' (duration: 134.43482ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T01:33:30.39716Z","caller":"traceutil/trace.go:171","msg":"trace[412545617] transaction","detail":"{read_only:false; response_revision:2393; number_of_response:1; }","duration":"183.591312ms","start":"2026-04-21T01:33:30.213534Z","end":"2026-04-21T01:33:30.397125Z","steps":["trace[412545617] 'process raft request' (duration: 162.789069ms)","trace[412545617] 'compare' (duration: 20.599249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T01:33:30.397362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.073773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:33:30.397447Z","caller":"traceutil/trace.go:171","msg":"trace[87103375] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:2393; }","duration":"130.185054ms","start":"2026-04-21T01:33:30.267242Z","end":"2026-04-21T01:33:30.397427Z","steps":["trace[87103375] 'agreement among raft nodes before linearized reading' (duration: 130.058922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:33:30.397453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.220657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T01:33:30.397532Z","caller":"traceutil/trace.go:171","msg":"trace[188499265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2393; }","duration":"158.319789ms","start":"2026-04-21T01:33:30.239192Z","end":"2026-04-21T01:33:30.397512Z","steps":["trace[188499265] 'agreement among raft nodes before linearized reading' (duration: 158.045044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:33:30.397701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.353582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T01:33:30.39775Z","caller":"traceutil/trace.go:171","msg":"trace[1876003377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2393; }","duration":"152.417063ms","start":"2026-04-21T01:33:30.245313Z","end":"2026-04-21T01:33:30.39773Z","steps":["trace[1876003377] 'agreement among raft nodes before linearized reading' (duration: 152.330971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:33:30.397762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.042041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-21T01:33:30.397798Z","caller":"traceutil/trace.go:171","msg":"trace[1731860880] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:2393; }","duration":"145.109623ms","start":"2026-04-21T01:33:30.252679Z","end":"2026-04-21T01:33:30.397788Z","steps":["trace[1731860880] 'agreement among raft nodes before linearized reading' (duration: 145.038111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:33:30.397827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.312488ms","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:531"} {"level":"info","ts":"2026-04-21T01:33:30.397898Z","caller":"traceutil/trace.go:171","msg":"trace[1223314222] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2393; }","duration":"153.384819ms","start":"2026-04-21T01:33:30.2445Z","end":"2026-04-21T01:33:30.397885Z","steps":["trace[1223314222] 'agreement among raft nodes before linearized reading' (duration: 153.218506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T01:33:30.398029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.866796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T01:33:30.398125Z","caller":"traceutil/trace.go:171","msg":"trace[1999908973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2393; }","duration":"160.009749ms","start":"2026-04-21T01:33:30.238095Z","end":"2026-04-21T01:33:30.398105Z","steps":["trace[1999908973] 'agreement among raft nodes before linearized reading' (duration: 159.072023ms)"],"step_count":1}