{"level":"warn","ts":"2026-04-20T08:02:53.00579Z","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-20T08:02:53.005869Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.228:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.228:2380","--initial-cluster=instance=https://199.19.213.228:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.228:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.228: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-20T08:02:53.006038Z","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-20T08:02:53.006162Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-04-20T08:02:53.006186Z","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-20T08:02:53.007816Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"]} {"level":"info","ts":"2026-04-20T08:02:53.00793Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.228: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-20T08:02:53.015845Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.916243ms"} {"level":"info","ts":"2026-04-20T08:02:53.026667Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db79120667ce701f","cluster-id":"793c581eb68f3646"} {"level":"info","ts":"2026-04-20T08:02:53.026871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T08:02:53.026905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 0"} {"level":"info","ts":"2026-04-20T08:02:53.026921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db79120667ce701f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T08:02:53.027019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 1"} {"level":"info","ts":"2026-04-20T08:02:53.027125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"warn","ts":"2026-04-20T08:02:53.035459Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T08:02:53.03725Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T08:02:53.038867Z","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-20T08:02:53.042824Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db79120667ce701f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T08:02:53.043832Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db79120667ce701f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T08:02:53.044031Z","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-20T08:02:53.044099Z","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-20T08:02:53.044527Z","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-20T08:02:53.044964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"info","ts":"2026-04-20T08:02:53.045307Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","added-peer-id":"db79120667ce701f","added-peer-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-04-20T08:02:53.046718Z","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-20T08:02:53.046828Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-04-20T08:02:53.046877Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-04-20T08:02:53.046887Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db79120667ce701f","initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T08:02:53.046914Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T08:02:53.12823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T08:02:53.128285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T08:02:53.128347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgPreVoteResp from db79120667ce701f at term 1"} {"level":"info","ts":"2026-04-20T08:02:53.128376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became candidate at term 2"} {"level":"info","ts":"2026-04-20T08:02:53.128423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgVoteResp from db79120667ce701f at term 2"} {"level":"info","ts":"2026-04-20T08:02:53.128448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became leader at term 2"} {"level":"info","ts":"2026-04-20T08:02:53.128496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db79120667ce701f elected leader db79120667ce701f at term 2"} {"level":"info","ts":"2026-04-20T08:02:53.12965Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:02:53.130363Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db79120667ce701f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.228:2379]}","request-path":"/0/members/db79120667ce701f/attributes","cluster-id":"793c581eb68f3646","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T08:02:53.130425Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:02:53.130491Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:02:53.130894Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T08:02:53.131Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T08:02:53.130985Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:02:53.131347Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:02:53.13151Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:02:53.132216Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.228:2379"} {"level":"info","ts":"2026-04-20T08:02:53.13442Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T08:02:57.429133Z","caller":"traceutil/trace.go:171","msg":"trace[2055634111] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"131.272857ms","start":"2026-04-20T08:02:57.297834Z","end":"2026-04-20T08:02:57.429107Z","steps":["trace[2055634111] 'read index received' (duration: 131.075383ms)","trace[2055634111] 'applied index is now lower than readState.Index' (duration: 196.514µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:02:57.429226Z","caller":"traceutil/trace.go:171","msg":"trace[569923169] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"493.86226ms","start":"2026-04-20T08:02:56.935322Z","end":"2026-04-20T08:02:57.429184Z","steps":["trace[569923169] 'process raft request' (duration: 493.648805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:02:57.42944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.499983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:02:57.429531Z","caller":"traceutil/trace.go:171","msg":"trace[506037335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:247; }","duration":"131.721197ms","start":"2026-04-20T08:02:57.297794Z","end":"2026-04-20T08:02:57.429515Z","steps":["trace[506037335] 'agreement among raft nodes before linearized reading' (duration: 131.497322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:02:57.430135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:02:56.935304Z","time spent":"494.021473ms","remote":"127.0.0.1:47580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:02:58.130269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079349609625543992,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T08:02:58.223694Z","caller":"traceutil/trace.go:171","msg":"trace[283879036] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:254; }","duration":"594.09388ms","start":"2026-04-20T08:02:57.629577Z","end":"2026-04-20T08:02:58.223671Z","steps":["trace[283879036] 'read index received' (duration: 588.976036ms)","trace[283879036] 'applied index is now lower than readState.Index' (duration: 5.117054ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:02:58.223873Z","caller":"traceutil/trace.go:171","msg":"trace[442304459] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"741.516034ms","start":"2026-04-20T08:02:57.482343Z","end":"2026-04-20T08:02:58.223859Z","steps":["trace[442304459] 'process raft request' (duration: 736.196596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:02:58.223927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.343115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:02:58.223966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:02:57.482317Z","time spent":"741.603416ms","remote":"127.0.0.1:47510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:02:58.224084Z","caller":"traceutil/trace.go:171","msg":"trace[1590397922] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"739.632833ms","start":"2026-04-20T08:02:57.48444Z","end":"2026-04-20T08:02:58.224073Z","steps":["trace[1590397922] 'process raft request' (duration: 739.176493ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:02:58.223976Z","caller":"traceutil/trace.go:171","msg":"trace[2005078272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:250; }","duration":"594.440077ms","start":"2026-04-20T08:02:57.629521Z","end":"2026-04-20T08:02:58.223961Z","steps":["trace[2005078272] 'agreement among raft nodes before linearized reading' (duration: 594.335685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:02:58.224183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.969256ms","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-20T08:02:58.224217Z","caller":"traceutil/trace.go:171","msg":"trace[679560981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:250; }","duration":"273.063517ms","start":"2026-04-20T08:02:57.951144Z","end":"2026-04-20T08:02:58.224207Z","steps":["trace[679560981] 'agreement among raft nodes before linearized reading' (duration: 273.012286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:02:58.224254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:02:57.629498Z","time spent":"594.719163ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T08:02:58.224213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:02:57.484414Z","time spent":"739.740785ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:03:23.306649Z","caller":"traceutil/trace.go:171","msg":"trace[2045345778] transaction","detail":"{read_only:false; number_of_response:1; response_revision:522; }","duration":"243.588996ms","start":"2026-04-20T08:03:23.063034Z","end":"2026-04-20T08:03:23.306623Z","steps":["trace[2045345778] 'process raft request' (duration: 243.492454ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.307085Z","caller":"traceutil/trace.go:171","msg":"trace[169323575] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"245.828055ms","start":"2026-04-20T08:03:23.061247Z","end":"2026-04-20T08:03:23.307075Z","steps":["trace[169323575] 'process raft request' (duration: 239.002474ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.307219Z","caller":"traceutil/trace.go:171","msg":"trace[526494556] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"244.765361ms","start":"2026-04-20T08:03:23.062444Z","end":"2026-04-20T08:03:23.307209Z","steps":["trace[526494556] 'process raft request' (duration: 244.027285ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.309933Z","caller":"traceutil/trace.go:171","msg":"trace[199548182] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"239.409173ms","start":"2026-04-20T08:03:23.070503Z","end":"2026-04-20T08:03:23.309912Z","steps":["trace[199548182] 'process raft request' (duration: 238.36041ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.309954Z","caller":"traceutil/trace.go:171","msg":"trace[1478095085] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"213.994029ms","start":"2026-04-20T08:03:23.095927Z","end":"2026-04-20T08:03:23.309921Z","steps":["trace[1478095085] 'process raft request' (duration: 213.279243ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.310138Z","caller":"traceutil/trace.go:171","msg":"trace[1306858375] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"212.507287ms","start":"2026-04-20T08:03:23.097617Z","end":"2026-04-20T08:03:23.310125Z","steps":["trace[1306858375] 'process raft request' (duration: 211.638828ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.310238Z","caller":"traceutil/trace.go:171","msg":"trace[474239429] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"228.90881ms","start":"2026-04-20T08:03:23.081314Z","end":"2026-04-20T08:03:23.310223Z","steps":["trace[474239429] 'process raft request' (duration: 227.660992ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.310294Z","caller":"traceutil/trace.go:171","msg":"trace[466668542] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"207.943826ms","start":"2026-04-20T08:03:23.102341Z","end":"2026-04-20T08:03:23.310284Z","steps":["trace[466668542] 'process raft request' (duration: 206.993995ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.310404Z","caller":"traceutil/trace.go:171","msg":"trace[1296871906] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"206.281928ms","start":"2026-04-20T08:03:23.104111Z","end":"2026-04-20T08:03:23.310392Z","steps":["trace[1296871906] 'process raft request' (duration: 205.282016ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.310494Z","caller":"traceutil/trace.go:171","msg":"trace[462434884] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"185.934798ms","start":"2026-04-20T08:03:23.124552Z","end":"2026-04-20T08:03:23.310487Z","steps":["trace[462434884] 'process raft request' (duration: 184.895066ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.310551Z","caller":"traceutil/trace.go:171","msg":"trace[511744242] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"227.164382ms","start":"2026-04-20T08:03:23.083376Z","end":"2026-04-20T08:03:23.31054Z","steps":["trace[511744242] 'process raft request' (duration: 225.639738ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.310644Z","caller":"traceutil/trace.go:171","msg":"trace[1138719062] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"225.25675ms","start":"2026-04-20T08:03:23.085379Z","end":"2026-04-20T08:03:23.310636Z","steps":["trace[1138719062] 'process raft request' (duration: 223.697956ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:23.310757Z","caller":"traceutil/trace.go:171","msg":"trace[1084996222] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"224.79998ms","start":"2026-04-20T08:03:23.085949Z","end":"2026-04-20T08:03:23.310748Z","steps":["trace[1084996222] 'process raft request' (duration: 223.209924ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:24.562825Z","caller":"traceutil/trace.go:171","msg":"trace[1194277096] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"382.791948ms","start":"2026-04-20T08:03:24.180005Z","end":"2026-04-20T08:03:24.562797Z","steps":["trace[1194277096] 'process raft request' (duration: 382.475102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:24.562986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:03:24.179975Z","time spent":"382.933272ms","remote":"127.0.0.1:47470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:03:24.569903Z","caller":"traceutil/trace.go:171","msg":"trace[462759412] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"155.408183ms","start":"2026-04-20T08:03:24.414452Z","end":"2026-04-20T08:03:24.56986Z","steps":["trace[462759412] 'read index received' (duration: 148.11134ms)","trace[462759412] 'applied index is now lower than readState.Index' (duration: 7.296173ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:03:24.570909Z","caller":"traceutil/trace.go:171","msg":"trace[2052269103] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"280.200977ms","start":"2026-04-20T08:03:24.290696Z","end":"2026-04-20T08:03:24.570897Z","steps":["trace[2052269103] 'process raft request' (duration: 278.700364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:24.571111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.644829ms","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-20T08:03:24.571135Z","caller":"traceutil/trace.go:171","msg":"trace[2094100208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"156.719941ms","start":"2026-04-20T08:03:24.414407Z","end":"2026-04-20T08:03:24.571127Z","steps":["trace[2094100208] 'agreement among raft nodes before linearized reading' (duration: 156.618369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:24.571275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.888415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T08:03:24.571292Z","caller":"traceutil/trace.go:171","msg":"trace[1303342284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"147.941197ms","start":"2026-04-20T08:03:24.423346Z","end":"2026-04-20T08:03:24.571287Z","steps":["trace[1303342284] 'agreement among raft nodes before linearized reading' (duration: 147.889006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:24.571844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.897988ms","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-20T08:03:24.571867Z","caller":"traceutil/trace.go:171","msg":"trace[480485508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"106.960469ms","start":"2026-04-20T08:03:24.4649Z","end":"2026-04-20T08:03:24.571861Z","steps":["trace[480485508] 'agreement among raft nodes before linearized reading' (duration: 106.897118ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:28.768012Z","caller":"traceutil/trace.go:171","msg":"trace[677865096] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"172.404499ms","start":"2026-04-20T08:03:28.595575Z","end":"2026-04-20T08:03:28.767979Z","steps":["trace[677865096] 'read index received' (duration: 172.227685ms)","trace[677865096] 'applied index is now lower than readState.Index' (duration: 175.794µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:03:28.768095Z","caller":"traceutil/trace.go:171","msg":"trace[204689319] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"172.511772ms","start":"2026-04-20T08:03:28.595548Z","end":"2026-04-20T08:03:28.768059Z","steps":["trace[204689319] 'process raft request' (duration: 172.251516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:28.76817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.566534ms","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-20T08:03:28.768208Z","caller":"traceutil/trace.go:171","msg":"trace[1704626771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"172.689586ms","start":"2026-04-20T08:03:28.595508Z","end":"2026-04-20T08:03:28.768198Z","steps":["trace[1704626771] 'agreement among raft nodes before linearized reading' (duration: 172.588424ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:28.768399Z","caller":"traceutil/trace.go:171","msg":"trace[368629167] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"169.645999ms","start":"2026-04-20T08:03:28.598736Z","end":"2026-04-20T08:03:28.768382Z","steps":["trace[368629167] 'process raft request' (duration: 169.585548ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:29.195303Z","caller":"traceutil/trace.go:171","msg":"trace[490070314] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"104.046474ms","start":"2026-04-20T08:03:29.091224Z","end":"2026-04-20T08:03:29.195271Z","steps":["trace[490070314] 'process raft request' (duration: 103.86758ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:29.651442Z","caller":"traceutil/trace.go:171","msg":"trace[1695730826] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"293.977082ms","start":"2026-04-20T08:03:29.35744Z","end":"2026-04-20T08:03:29.651417Z","steps":["trace[1695730826] 'read index received' (duration: 293.212765ms)","trace[1695730826] 'applied index is now lower than readState.Index' (duration: 763.407µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:03:29.651516Z","caller":"traceutil/trace.go:171","msg":"trace[2099451257] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"331.417371ms","start":"2026-04-20T08:03:29.320077Z","end":"2026-04-20T08:03:29.651494Z","steps":["trace[2099451257] 'process raft request' (duration: 330.760296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:29.651776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:03:29.32004Z","time spent":"331.603215ms","remote":"127.0.0.1:47510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:03:29.651883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.389881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gmwlj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T08:03:29.652022Z","caller":"traceutil/trace.go:171","msg":"trace[882533686] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gmwlj; range_end:; response_count:1; response_revision:618; }","duration":"294.551764ms","start":"2026-04-20T08:03:29.357436Z","end":"2026-04-20T08:03:29.651987Z","steps":["trace[882533686] 'agreement among raft nodes before linearized reading' (duration: 294.104785ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:29.665138Z","caller":"traceutil/trace.go:171","msg":"trace[1796787302] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"168.819059ms","start":"2026-04-20T08:03:29.496299Z","end":"2026-04-20T08:03:29.665118Z","steps":["trace[1796787302] 'process raft request' (duration: 168.483081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:30.476085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.301544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:03:30.476337Z","caller":"traceutil/trace.go:171","msg":"trace[264044889] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"797.72952ms","start":"2026-04-20T08:03:29.678588Z","end":"2026-04-20T08:03:30.476317Z","steps":["trace[264044889] 'process raft request' (duration: 667.862914ms)","trace[264044889] 'compare' (duration: 126.44045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:03:30.476422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:03:29.67856Z","time spent":"797.832231ms","remote":"127.0.0.1:47590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:03:30.987861Z","caller":"traceutil/trace.go:171","msg":"trace[1074392215] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"894.638117ms","start":"2026-04-20T08:03:30.093192Z","end":"2026-04-20T08:03:30.98783Z","steps":["trace[1074392215] 'process raft request' (duration: 894.564375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:30.988026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:03:30.09316Z","time spent":"894.78099ms","remote":"127.0.0.1:47580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:03:30.988193Z","caller":"traceutil/trace.go:171","msg":"trace[50229863] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.269194993s","start":"2026-04-20T08:03:29.718942Z","end":"2026-04-20T08:03:30.988137Z","steps":["trace[50229863] 'process raft request' (duration: 1.268650351s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:30.988358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:03:29.718912Z","time spent":"1.269376958s","remote":"127.0.0.1:47768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:03:30.988516Z","caller":"traceutil/trace.go:171","msg":"trace[1470335061] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:639; }","duration":"401.979074ms","start":"2026-04-20T08:03:30.586476Z","end":"2026-04-20T08:03:30.988455Z","steps":["trace[1470335061] 'read index received' (duration: 401.960534ms)","trace[1470335061] 'applied index is now lower than readState.Index' (duration: 15.76µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:03:30.988694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.209809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:30.988749Z","caller":"traceutil/trace.go:171","msg":"trace[279741148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"402.313551ms","start":"2026-04-20T08:03:30.586421Z","end":"2026-04-20T08:03:30.988735Z","steps":["trace[279741148] 'agreement among raft nodes before linearized reading' (duration: 402.226399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:30.988784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:03:30.586398Z","time spent":"402.378732ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:03:30.988951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.902442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T08:03:30.989039Z","caller":"traceutil/trace.go:171","msg":"trace[136844503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"216.052656ms","start":"2026-04-20T08:03:30.772967Z","end":"2026-04-20T08:03:30.98902Z","steps":["trace[136844503] 'agreement among raft nodes before linearized reading' (duration: 215.710308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:30.989111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.131482ms","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-20T08:03:30.989002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.278935ms","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-20T08:03:30.989169Z","caller":"traceutil/trace.go:171","msg":"trace[1559448770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"214.232004ms","start":"2026-04-20T08:03:30.774919Z","end":"2026-04-20T08:03:30.989151Z","steps":["trace[1559448770] 'agreement among raft nodes before linearized reading' (duration: 214.056341ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:30.989264Z","caller":"traceutil/trace.go:171","msg":"trace[1219949648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"204.542591ms","start":"2026-04-20T08:03:30.784661Z","end":"2026-04-20T08:03:30.989204Z","steps":["trace[1219949648] 'agreement among raft nodes before linearized reading' (duration: 204.171522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:31.712211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.222133ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T08:03:32.806704Z","caller":"traceutil/trace.go:171","msg":"trace[386168333] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"219.755557ms","start":"2026-04-20T08:03:32.586918Z","end":"2026-04-20T08:03:32.806673Z","steps":["trace[386168333] 'read index received' (duration: 219.663705ms)","trace[386168333] 'applied index is now lower than readState.Index' (duration: 90.502µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:03:32.806744Z","caller":"traceutil/trace.go:171","msg":"trace[375850682] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"239.017685ms","start":"2026-04-20T08:03:32.567704Z","end":"2026-04-20T08:03:32.806722Z","steps":["trace[375850682] 'process raft request' (duration: 238.81299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:32.806947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.020783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:32.80699Z","caller":"traceutil/trace.go:171","msg":"trace[23089410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"220.126925ms","start":"2026-04-20T08:03:32.586853Z","end":"2026-04-20T08:03:32.80698Z","steps":["trace[23089410] 'agreement among raft nodes before linearized reading' (duration: 219.964992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:32.815091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.924483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:32.81517Z","caller":"traceutil/trace.go:171","msg":"trace[1518334876] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:661; }","duration":"184.053726ms","start":"2026-04-20T08:03:32.631097Z","end":"2026-04-20T08:03:32.81515Z","steps":["trace[1518334876] 'agreement among raft nodes before linearized reading' (duration: 183.906473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:32.815472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.187762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:32.8155Z","caller":"traceutil/trace.go:171","msg":"trace[484656814] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:661; }","duration":"167.267723ms","start":"2026-04-20T08:03:32.648225Z","end":"2026-04-20T08:03:32.815493Z","steps":["trace[484656814] 'agreement among raft nodes before linearized reading' (duration: 167.223482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:32.815621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.760875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:32.815646Z","caller":"traceutil/trace.go:171","msg":"trace[629725232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:661; }","duration":"167.799836ms","start":"2026-04-20T08:03:32.647839Z","end":"2026-04-20T08:03:32.815639Z","steps":["trace[629725232] 'agreement among raft nodes before linearized reading' (duration: 167.761225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:32.815736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.124724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:32.815759Z","caller":"traceutil/trace.go:171","msg":"trace[1088253892] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:661; }","duration":"168.171125ms","start":"2026-04-20T08:03:32.647581Z","end":"2026-04-20T08:03:32.815753Z","steps":["trace[1088253892] 'agreement among raft nodes before linearized reading' (duration: 168.134084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:32.815847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.943774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:32.815871Z","caller":"traceutil/trace.go:171","msg":"trace[1726893167] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:661; }","duration":"184.000695ms","start":"2026-04-20T08:03:32.631863Z","end":"2026-04-20T08:03:32.815864Z","steps":["trace[1726893167] 'agreement among raft nodes before linearized reading' (duration: 183.964965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:32.81596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.794423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:32.816053Z","caller":"traceutil/trace.go:171","msg":"trace[758525948] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:661; }","duration":"184.882125ms","start":"2026-04-20T08:03:32.631138Z","end":"2026-04-20T08:03:32.81602Z","steps":["trace[758525948] 'agreement among raft nodes before linearized reading' (duration: 184.756422ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:35.69819Z","caller":"traceutil/trace.go:171","msg":"trace[1520186863] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"113.815371ms","start":"2026-04-20T08:03:35.584346Z","end":"2026-04-20T08:03:35.698161Z","steps":["trace[1520186863] 'read index received' (duration: 113.581606ms)","trace[1520186863] 'applied index is now lower than readState.Index' (duration: 232.215µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:03:35.698238Z","caller":"traceutil/trace.go:171","msg":"trace[168641390] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"126.039762ms","start":"2026-04-20T08:03:35.572157Z","end":"2026-04-20T08:03:35.698197Z","steps":["trace[168641390] 'process raft request' (duration: 125.698084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:35.698355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.993394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:35.698407Z","caller":"traceutil/trace.go:171","msg":"trace[1044647230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"114.104188ms","start":"2026-04-20T08:03:35.584286Z","end":"2026-04-20T08:03:35.698391Z","steps":["trace[1044647230] 'agreement among raft nodes before linearized reading' (duration: 113.988284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:43.399193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.78559ms","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-20T08:03:43.399294Z","caller":"traceutil/trace.go:171","msg":"trace[1775340211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"106.952404ms","start":"2026-04-20T08:03:43.292321Z","end":"2026-04-20T08:03:43.399274Z","steps":["trace[1775340211] 'range keys from in-memory index tree' (duration: 106.585816ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:45.816652Z","caller":"traceutil/trace.go:171","msg":"trace[1130001091] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"230.526578ms","start":"2026-04-20T08:03:45.5861Z","end":"2026-04-20T08:03:45.816626Z","steps":["trace[1130001091] 'read index received' (duration: 230.274562ms)","trace[1130001091] 'applied index is now lower than readState.Index' (duration: 250.906µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:03:45.816843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.720022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:03:45.81688Z","caller":"traceutil/trace.go:171","msg":"trace[938877468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"231.030559ms","start":"2026-04-20T08:03:45.585836Z","end":"2026-04-20T08:03:45.816867Z","steps":["trace[938877468] 'agreement among raft nodes before linearized reading' (duration: 230.921557ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:03:45.817321Z","caller":"traceutil/trace.go:171","msg":"trace[131754746] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"351.896964ms","start":"2026-04-20T08:03:45.465407Z","end":"2026-04-20T08:03:45.817304Z","steps":["trace[131754746] 'process raft request' (duration: 351.060204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:03:45.817431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:03:45.465376Z","time spent":"351.983676ms","remote":"127.0.0.1:47590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:03:46.076897Z","caller":"traceutil/trace.go:171","msg":"trace[1347579498] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"120.302192ms","start":"2026-04-20T08:03:45.95657Z","end":"2026-04-20T08:03:46.076872Z","steps":["trace[1347579498] 'process raft request' (duration: 118.754848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:23.123522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.134494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:04:23.123671Z","caller":"traceutil/trace.go:171","msg":"trace[1751411687] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1007; }","duration":"284.355939ms","start":"2026-04-20T08:04:22.839287Z","end":"2026-04-20T08:04:23.123643Z","steps":["trace[1751411687] 'count revisions from in-memory index tree' (duration: 283.98426ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:23.506859Z","caller":"traceutil/trace.go:171","msg":"trace[482163937] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"165.308464ms","start":"2026-04-20T08:04:23.341527Z","end":"2026-04-20T08:04:23.506836Z","steps":["trace[482163937] 'process raft request' (duration: 165.168921ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:23.923991Z","caller":"traceutil/trace.go:171","msg":"trace[1783802694] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"566.691911ms","start":"2026-04-20T08:04:23.357269Z","end":"2026-04-20T08:04:23.923961Z","steps":["trace[1783802694] 'read index received' (duration: 149.520873ms)","trace[1783802694] 'applied index is now lower than readState.Index' (duration: 417.169668ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:04:23.924114Z","caller":"traceutil/trace.go:171","msg":"trace[1306186564] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"579.661461ms","start":"2026-04-20T08:04:23.344424Z","end":"2026-04-20T08:04:23.924085Z","steps":["trace[1306186564] 'process raft request' (duration: 577.906921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:23.924261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.960128ms","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-20T08:04:23.924269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:04:23.344391Z","time spent":"579.799463ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:04:23.924306Z","caller":"traceutil/trace.go:171","msg":"trace[419758619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"567.05979ms","start":"2026-04-20T08:04:23.357234Z","end":"2026-04-20T08:04:23.924294Z","steps":["trace[419758619] 'agreement among raft nodes before linearized reading' (duration: 566.872906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:23.924346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:04:23.357218Z","time spent":"567.119841ms","remote":"127.0.0.1:47678","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-20T08:04:23.924471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.512913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:04:23.924498Z","caller":"traceutil/trace.go:171","msg":"trace[331480302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"337.585516ms","start":"2026-04-20T08:04:23.586904Z","end":"2026-04-20T08:04:23.92449Z","steps":["trace[331480302] 'agreement among raft nodes before linearized reading' (duration: 337.547195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:23.924522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:04:23.586877Z","time spent":"337.639546ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:04:23.924734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.951278ms","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-20T08:04:23.924781Z","caller":"traceutil/trace.go:171","msg":"trace[1730465729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"318.03407ms","start":"2026-04-20T08:04:23.606736Z","end":"2026-04-20T08:04:23.92477Z","steps":["trace[1730465729] 'agreement among raft nodes before linearized reading' (duration: 317.895407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:23.924813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:04:23.606708Z","time spent":"318.098201ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T08:04:24.614577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.88786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:04:24.614754Z","caller":"traceutil/trace.go:171","msg":"trace[1099604356] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"685.134601ms","start":"2026-04-20T08:04:23.929605Z","end":"2026-04-20T08:04:24.614739Z","steps":["trace[1099604356] 'process raft request' (duration: 685.08327ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:24.614792Z","caller":"traceutil/trace.go:171","msg":"trace[652814454] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"685.679584ms","start":"2026-04-20T08:04:23.929092Z","end":"2026-04-20T08:04:24.614771Z","steps":["trace[652814454] 'process raft request' (duration: 396.531379ms)","trace[652814454] 'compare' (duration: 288.613143ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:04:24.614808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:04:23.929582Z","time spent":"685.204084ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:04:24.614886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:04:23.929074Z","time spent":"685.759136ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:04:48.391501Z","caller":"traceutil/trace.go:171","msg":"trace[215360967] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"305.493358ms","start":"2026-04-20T08:04:48.085971Z","end":"2026-04-20T08:04:48.391464Z","steps":["trace[215360967] 'process raft request' (duration: 305.276483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:48.391713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:04:48.085943Z","time spent":"305.656782ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:04:48.733025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.352951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:04:48.733125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.423211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:04:48.733256Z","caller":"traceutil/trace.go:171","msg":"trace[1994719007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"143.74884ms","start":"2026-04-20T08:04:48.589488Z","end":"2026-04-20T08:04:48.733237Z","steps":["trace[1994719007] 'range keys from in-memory index tree' (duration: 143.189168ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:48.733436Z","caller":"traceutil/trace.go:171","msg":"trace[1957517522] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1089; }","duration":"237.787909ms","start":"2026-04-20T08:04:48.495631Z","end":"2026-04-20T08:04:48.733419Z","steps":["trace[1957517522] 'count revisions from in-memory index tree' (duration: 237.277138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:48.733175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.532257ms","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-20T08:04:48.73356Z","caller":"traceutil/trace.go:171","msg":"trace[1082397769] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1089; }","duration":"187.969477ms","start":"2026-04-20T08:04:48.545577Z","end":"2026-04-20T08:04:48.733547Z","steps":["trace[1082397769] 'count revisions from in-memory index tree' (duration: 187.431325ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:48.941529Z","caller":"traceutil/trace.go:171","msg":"trace[574356426] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"202.739136ms","start":"2026-04-20T08:04:48.738739Z","end":"2026-04-20T08:04:48.941478Z","steps":["trace[574356426] 'process raft request' (duration: 201.992839ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:49.017706Z","caller":"traceutil/trace.go:171","msg":"trace[129446675] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"278.368555ms","start":"2026-04-20T08:04:48.739309Z","end":"2026-04-20T08:04:49.017677Z","steps":["trace[129446675] 'process raft request' (duration: 278.121749ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:49.017973Z","caller":"traceutil/trace.go:171","msg":"trace[704686967] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1125; }","duration":"211.929631ms","start":"2026-04-20T08:04:48.805996Z","end":"2026-04-20T08:04:49.017925Z","steps":["trace[704686967] 'read index received' (duration: 134.730768ms)","trace[704686967] 'applied index is now lower than readState.Index' (duration: 77.195593ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:04:49.018153Z","caller":"traceutil/trace.go:171","msg":"trace[220307219] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"223.670793ms","start":"2026-04-20T08:04:48.794449Z","end":"2026-04-20T08:04:49.01812Z","steps":["trace[220307219] 'process raft request' (duration: 223.171592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:49.018348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.315379ms","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-20T08:04:49.018428Z","caller":"traceutil/trace.go:171","msg":"trace[975136737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"212.478883ms","start":"2026-04-20T08:04:48.805927Z","end":"2026-04-20T08:04:49.018406Z","steps":["trace[975136737] 'agreement among raft nodes before linearized reading' (duration: 212.212837ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:49.758547Z","caller":"traceutil/trace.go:171","msg":"trace[967653579] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"114.770062ms","start":"2026-04-20T08:04:49.643743Z","end":"2026-04-20T08:04:49.758513Z","steps":["trace[967653579] 'process raft request' (duration: 114.544437ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:51.65392Z","caller":"traceutil/trace.go:171","msg":"trace[312731638] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"220.431291ms","start":"2026-04-20T08:04:51.43346Z","end":"2026-04-20T08:04:51.653891Z","steps":["trace[312731638] 'process raft request' (duration: 220.262597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:51.942943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.207556ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T08:04:51.943066Z","caller":"traceutil/trace.go:171","msg":"trace[308179526] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"179.937107ms","start":"2026-04-20T08:04:51.763109Z","end":"2026-04-20T08:04:51.943046Z","steps":["trace[308179526] 'read index received' (duration: 44.091µs)","trace[308179526] 'applied index is now lower than readState.Index' (duration: 179.891376ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:04:51.943254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.138491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T08:04:51.943295Z","caller":"traceutil/trace.go:171","msg":"trace[451724248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1100; }","duration":"180.233543ms","start":"2026-04-20T08:04:51.763051Z","end":"2026-04-20T08:04:51.943284Z","steps":["trace[451724248] 'agreement among raft nodes before linearized reading' (duration: 180.048589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:04:52.654127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.810773ms","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-20T08:04:52.6542Z","caller":"traceutil/trace.go:171","msg":"trace[487258732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1101; }","duration":"166.922566ms","start":"2026-04-20T08:04:52.48726Z","end":"2026-04-20T08:04:52.654182Z","steps":["trace[487258732] 'range keys from in-memory index tree' (duration: 166.63258ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:04:52.767427Z","caller":"traceutil/trace.go:171","msg":"trace[148983336] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"108.541774ms","start":"2026-04-20T08:04:52.658859Z","end":"2026-04-20T08:04:52.7674Z","steps":["trace[148983336] 'process raft request' (duration: 108.400281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:07.11654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.07007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.228\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-20T08:05:07.116614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.175621ms","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-20T08:05:07.116608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.881504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:05:07.116663Z","caller":"traceutil/trace.go:171","msg":"trace[1563569029] range","detail":"{range_begin:/registry/masterleases/199.19.213.228; range_end:; response_count:1; response_revision:1197; }","duration":"843.244874ms","start":"2026-04-20T08:05:06.273393Z","end":"2026-04-20T08:05:07.116638Z","steps":["trace[1563569029] 'range keys from in-memory index tree' (duration: 842.856506ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:07.116683Z","caller":"traceutil/trace.go:171","msg":"trace[1545507177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1197; }","duration":"532.027877ms","start":"2026-04-20T08:05:06.584639Z","end":"2026-04-20T08:05:07.116667Z","steps":["trace[1545507177] 'range keys from in-memory index tree' (duration: 531.759091ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:07.116674Z","caller":"traceutil/trace.go:171","msg":"trace[1318429947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1197; }","duration":"291.280033ms","start":"2026-04-20T08:05:06.825376Z","end":"2026-04-20T08:05:07.116656Z","steps":["trace[1318429947] 'range keys from in-memory index tree' (duration: 291.011877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:07.11672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:06.584603Z","time spent":"532.105159ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:05:07.116721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:06.273371Z","time spent":"843.333767ms","remote":"127.0.0.1:47454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.228\" "} {"level":"info","ts":"2026-04-20T08:05:07.62838Z","caller":"traceutil/trace.go:171","msg":"trace[646894725] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"506.013677ms","start":"2026-04-20T08:05:07.122336Z","end":"2026-04-20T08:05:07.62835Z","steps":["trace[646894725] 'process raft request' (duration: 505.76313ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:07.628561Z","caller":"traceutil/trace.go:171","msg":"trace[1430922840] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1239; }","duration":"458.311782ms","start":"2026-04-20T08:05:07.170225Z","end":"2026-04-20T08:05:07.628536Z","steps":["trace[1430922840] 'read index received' (duration: 40.537935ms)","trace[1430922840] 'applied index is now lower than readState.Index' (duration: 417.768387ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:07.628618Z","caller":"traceutil/trace.go:171","msg":"trace[1552450501] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"505.498335ms","start":"2026-04-20T08:05:07.1231Z","end":"2026-04-20T08:05:07.628598Z","steps":["trace[1552450501] 'process raft request' (duration: 505.205609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:07.628704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:07.123085Z","time spent":"505.566436ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:05:07.628756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.517166ms","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-20T08:05:07.628779Z","caller":"traceutil/trace.go:171","msg":"trace[982984740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1200; }","duration":"458.593098ms","start":"2026-04-20T08:05:07.170179Z","end":"2026-04-20T08:05:07.628773Z","steps":["trace[982984740] 'agreement among raft nodes before linearized reading' (duration: 458.455265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:07.628797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:07.170149Z","time spent":"458.644228ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T08:05:07.628888Z","caller":"traceutil/trace.go:171","msg":"trace[1356842246] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"416.932017ms","start":"2026-04-20T08:05:07.21194Z","end":"2026-04-20T08:05:07.628872Z","steps":["trace[1356842246] 'process raft request' (duration: 416.774074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:07.628948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:07.211913Z","time spent":"417.004249ms","remote":"127.0.0.1:47454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:05:07.629023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.228477ms","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-20T08:05:07.629092Z","caller":"traceutil/trace.go:171","msg":"trace[314266622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1201; }","duration":"457.33514ms","start":"2026-04-20T08:05:07.171738Z","end":"2026-04-20T08:05:07.629073Z","steps":["trace[314266622] 'agreement among raft nodes before linearized reading' (duration: 457.091154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:07.62914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:07.171726Z","time spent":"457.405661ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T08:05:07.629261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:07.122314Z","time spent":"506.147279ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:05:20.09722Z","caller":"traceutil/trace.go:171","msg":"trace[1125331691] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1283; }","duration":"389.227709ms","start":"2026-04-20T08:05:19.707975Z","end":"2026-04-20T08:05:20.097203Z","steps":["trace[1125331691] 'read index received' (duration: 49.733759ms)","trace[1125331691] 'applied index is now lower than readState.Index' (duration: 339.49316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:20.097703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.666138ms","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-20T08:05:20.097777Z","caller":"traceutil/trace.go:171","msg":"trace[1285022481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"384.782961ms","start":"2026-04-20T08:05:19.712974Z","end":"2026-04-20T08:05:20.097757Z","steps":["trace[1285022481] 'agreement among raft nodes before linearized reading' (duration: 384.519745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:20.097843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:19.712961Z","time spent":"384.873213ms","remote":"127.0.0.1:47678","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-20T08:05:20.098038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.060967ms","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-20T08:05:20.098041Z","caller":"traceutil/trace.go:171","msg":"trace[804502301] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"409.033481ms","start":"2026-04-20T08:05:19.688993Z","end":"2026-04-20T08:05:20.098026Z","steps":["trace[804502301] 'process raft request' (duration: 406.126936ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:20.098093Z","caller":"traceutil/trace.go:171","msg":"trace[358218581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"390.120409ms","start":"2026-04-20T08:05:19.707935Z","end":"2026-04-20T08:05:20.098056Z","steps":["trace[358218581] 'agreement among raft nodes before linearized reading' (duration: 390.038917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:20.098123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:19.707919Z","time spent":"390.19695ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T08:05:20.098391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:19.688972Z","time spent":"409.120664ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:05:21.528716Z","caller":"traceutil/trace.go:171","msg":"trace[1120728250] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"366.240976ms","start":"2026-04-20T08:05:21.162448Z","end":"2026-04-20T08:05:21.528689Z","steps":["trace[1120728250] 'process raft request' (duration: 366.039711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:21.528893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:21.16242Z","time spent":"366.37741ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:05:22.108461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.918352ms","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-20T08:05:22.108546Z","caller":"traceutil/trace.go:171","msg":"trace[177368308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"346.054406ms","start":"2026-04-20T08:05:21.762472Z","end":"2026-04-20T08:05:22.108526Z","steps":["trace[177368308] 'range keys from in-memory index tree' (duration: 345.764419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:22.108586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:21.762446Z","time spent":"346.131338ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T08:05:22.117068Z","caller":"traceutil/trace.go:171","msg":"trace[1680814764] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"180.241395ms","start":"2026-04-20T08:05:21.936792Z","end":"2026-04-20T08:05:22.117033Z","steps":["trace[1680814764] 'process raft request' (duration: 179.947507ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:24.24329Z","caller":"traceutil/trace.go:171","msg":"trace[1980182665] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"114.203179ms","start":"2026-04-20T08:05:24.129059Z","end":"2026-04-20T08:05:24.243262Z","steps":["trace[1980182665] 'read index received' (duration: 114.161678ms)","trace[1980182665] 'applied index is now lower than readState.Index' (duration: 40.071µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:24.243619Z","caller":"traceutil/trace.go:171","msg":"trace[59703191] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"115.53823ms","start":"2026-04-20T08:05:24.128064Z","end":"2026-04-20T08:05:24.243602Z","steps":["trace[59703191] 'process raft request' (duration: 115.037608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:24.244046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.968716ms","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-20T08:05:24.244209Z","caller":"traceutil/trace.go:171","msg":"trace[1357336701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"115.13515ms","start":"2026-04-20T08:05:24.129Z","end":"2026-04-20T08:05:24.244135Z","steps":["trace[1357336701] 'agreement among raft nodes before linearized reading' (duration: 114.903425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:24.245376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.617077ms","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-20T08:05:24.245432Z","caller":"traceutil/trace.go:171","msg":"trace[2025064746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"109.695269ms","start":"2026-04-20T08:05:24.135723Z","end":"2026-04-20T08:05:24.245419Z","steps":["trace[2025064746] 'agreement among raft nodes before linearized reading' (duration: 109.518015ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:24.24571Z","caller":"traceutil/trace.go:171","msg":"trace[1660453065] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"113.936993ms","start":"2026-04-20T08:05:24.131759Z","end":"2026-04-20T08:05:24.245696Z","steps":["trace[1660453065] 'process raft request' (duration: 113.398571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:28.832076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079349609625546260,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T08:05:28.88948Z","caller":"traceutil/trace.go:171","msg":"trace[1168208614] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"575.152069ms","start":"2026-04-20T08:05:28.3143Z","end":"2026-04-20T08:05:28.889452Z","steps":["trace[1168208614] 'process raft request' (duration: 574.863563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:28.889681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:28.314282Z","time spent":"575.271912ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:05:29.46304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.28084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:05:29.463265Z","caller":"traceutil/trace.go:171","msg":"trace[1343702997] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"544.510565ms","start":"2026-04-20T08:05:28.918736Z","end":"2026-04-20T08:05:29.463246Z","steps":["trace[1343702997] 'process raft request' (duration: 544.413933ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:29.463279Z","caller":"traceutil/trace.go:171","msg":"trace[111046495] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1358; }","duration":"1.132110433s","start":"2026-04-20T08:05:28.331141Z","end":"2026-04-20T08:05:29.463251Z","steps":["trace[111046495] 'read index received' (duration: 558.031707ms)","trace[111046495] 'applied index is now lower than readState.Index' (duration: 574.076425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:29.463346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:28.918713Z","time spent":"544.593997ms","remote":"127.0.0.1:47590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1768,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:05:29.463621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131229313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-20T08:05:29.463654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.330749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:05:29.46369Z","caller":"traceutil/trace.go:171","msg":"trace[348414356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1315; }","duration":"1.131358727s","start":"2026-04-20T08:05:28.332316Z","end":"2026-04-20T08:05:29.463674Z","steps":["trace[348414356] 'agreement among raft nodes before linearized reading' (duration: 1.131098411s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:29.463703Z","caller":"traceutil/trace.go:171","msg":"trace[1205128349] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1315; }","duration":"418.42083ms","start":"2026-04-20T08:05:29.045267Z","end":"2026-04-20T08:05:29.463688Z","steps":["trace[1205128349] 'agreement among raft nodes before linearized reading' (duration: 418.303688ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:29.463643Z","caller":"traceutil/trace.go:171","msg":"trace[442790343] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"965.676038ms","start":"2026-04-20T08:05:28.497943Z","end":"2026-04-20T08:05:29.463619Z","steps":["trace[442790343] 'process raft request' (duration: 790.698812ms)","trace[442790343] 'compare' (duration: 174.020705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:29.46379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132452051s","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-20T08:05:29.463796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.20446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:05:29.463847Z","caller":"traceutil/trace.go:171","msg":"trace[1953703494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"1.132728736s","start":"2026-04-20T08:05:28.331098Z","end":"2026-04-20T08:05:29.463827Z","steps":["trace[1953703494] 'agreement among raft nodes before linearized reading' (duration: 1.132310557s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:29.463858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120994865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T08:05:29.463907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:28.331077Z","time spent":"1.132821149s","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T08:05:29.463914Z","caller":"traceutil/trace.go:171","msg":"trace[2097193333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"1.121081467s","start":"2026-04-20T08:05:28.34282Z","end":"2026-04-20T08:05:29.463901Z","steps":["trace[2097193333] 'agreement among raft nodes before linearized reading' (duration: 1.120955984s)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:29.463914Z","caller":"traceutil/trace.go:171","msg":"trace[712130134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"876.385905ms","start":"2026-04-20T08:05:28.587503Z","end":"2026-04-20T08:05:29.463889Z","steps":["trace[712130134] 'agreement among raft nodes before linearized reading' (duration: 876.143119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:29.46396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:28.342805Z","time spent":"1.121140658s","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T08:05:29.463976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:28.587483Z","time spent":"876.482977ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:05:29.464067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:28.497904Z","time spent":"965.900713ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:05:29.463742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:29.045247Z","time spent":"418.485652ms","remote":"127.0.0.1:47470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-20T08:05:29.46374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:28.33229Z","time spent":"1.131434848s","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T08:05:29.463717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103636827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T08:05:29.464369Z","caller":"traceutil/trace.go:171","msg":"trace[1332193198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"1.104305593s","start":"2026-04-20T08:05:28.360045Z","end":"2026-04-20T08:05:29.464351Z","steps":["trace[1332193198] 'agreement among raft nodes before linearized reading' (duration: 1.103597037s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:29.464441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:28.360035Z","time spent":"1.104387244s","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T08:05:29.683209Z","caller":"traceutil/trace.go:171","msg":"trace[283371116] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"213.22873ms","start":"2026-04-20T08:05:29.469947Z","end":"2026-04-20T08:05:29.683176Z","steps":["trace[283371116] 'process raft request' (duration: 213.008545ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:29.683246Z","caller":"traceutil/trace.go:171","msg":"trace[991235517] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1362; }","duration":"132.166391ms","start":"2026-04-20T08:05:29.551062Z","end":"2026-04-20T08:05:29.683228Z","steps":["trace[991235517] 'read index received' (duration: 131.874765ms)","trace[991235517] 'applied index is now lower than readState.Index' (duration: 290.526µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:29.68341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.688304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-04-20T08:05:29.683441Z","caller":"traceutil/trace.go:171","msg":"trace[934717815] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1320; }","duration":"172.771027ms","start":"2026-04-20T08:05:29.510661Z","end":"2026-04-20T08:05:29.683432Z","steps":["trace[934717815] 'agreement among raft nodes before linearized reading' (duration: 172.637074ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:29.683512Z","caller":"traceutil/trace.go:171","msg":"trace[940176566] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"213.315963ms","start":"2026-04-20T08:05:29.470183Z","end":"2026-04-20T08:05:29.683499Z","steps":["trace[940176566] 'process raft request' (duration: 212.974084ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:29.683588Z","caller":"traceutil/trace.go:171","msg":"trace[758243533] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"213.540068ms","start":"2026-04-20T08:05:29.470025Z","end":"2026-04-20T08:05:29.683565Z","steps":["trace[758243533] 'process raft request' (duration: 213.101838ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:29.683676Z","caller":"traceutil/trace.go:171","msg":"trace[1394526151] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"212.882853ms","start":"2026-04-20T08:05:29.470787Z","end":"2026-04-20T08:05:29.683669Z","steps":["trace[1394526151] 'process raft request' (duration: 212.403841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:29.683949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.249668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-04-20T08:05:29.684069Z","caller":"traceutil/trace.go:171","msg":"trace[463002205] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1320; }","duration":"164.433992ms","start":"2026-04-20T08:05:29.51961Z","end":"2026-04-20T08:05:29.684044Z","steps":["trace[463002205] 'agreement among raft nodes before linearized reading' (duration: 164.076974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:29.916642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.903057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:05:29.916842Z","caller":"traceutil/trace.go:171","msg":"trace[382189117] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1366; }","duration":"229.36152ms","start":"2026-04-20T08:05:29.687463Z","end":"2026-04-20T08:05:29.916825Z","steps":["trace[382189117] 'read index received' (duration: 109.075045ms)","trace[382189117] 'applied index is now lower than readState.Index' (duration: 120.285475ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:29.916938Z","caller":"traceutil/trace.go:171","msg":"trace[1558650188] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"257.131171ms","start":"2026-04-20T08:05:29.659796Z","end":"2026-04-20T08:05:29.916927Z","steps":["trace[1558650188] 'process raft request' (duration: 256.951017ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:29.917143Z","caller":"traceutil/trace.go:171","msg":"trace[228649793] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"359.057746ms","start":"2026-04-20T08:05:29.558011Z","end":"2026-04-20T08:05:29.917068Z","steps":["trace[228649793] 'process raft request' (duration: 238.586446ms)","trace[228649793] 'compare' (duration: 119.795845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:29.917251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.78142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-04-20T08:05:29.917298Z","caller":"traceutil/trace.go:171","msg":"trace[235982716] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1322; }","duration":"229.863051ms","start":"2026-04-20T08:05:29.687421Z","end":"2026-04-20T08:05:29.917284Z","steps":["trace[235982716] 'agreement among raft nodes before linearized reading' (duration: 229.77885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:29.917373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:29.558Z","time spent":"359.25432ms","remote":"127.0.0.1:47590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2708,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:05:29.917539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.028735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-dac3cd1c-29e8-4cfc-bc5a-fda62ddb3bb2\" ","response":"range_response_count:1 size:2174"} {"level":"info","ts":"2026-04-20T08:05:29.917579Z","caller":"traceutil/trace.go:171","msg":"trace[1277455212] range","detail":"{range_begin:/registry/persistentvolumes/pvc-dac3cd1c-29e8-4cfc-bc5a-fda62ddb3bb2; range_end:; response_count:1; response_revision:1322; }","duration":"230.077676ms","start":"2026-04-20T08:05:29.687492Z","end":"2026-04-20T08:05:29.917569Z","steps":["trace[1277455212] 'agreement among raft nodes before linearized reading' (duration: 229.950033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:30.401246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.055914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:05:30.401348Z","caller":"traceutil/trace.go:171","msg":"trace[640584171] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"374.96128ms","start":"2026-04-20T08:05:30.026371Z","end":"2026-04-20T08:05:30.401332Z","steps":["trace[640584171] 'read index received' (duration: 24.675141ms)","trace[640584171] 'applied index is now lower than readState.Index' (duration: 350.284739ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:30.401579Z","caller":"traceutil/trace.go:171","msg":"trace[886916242] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"388.667716ms","start":"2026-04-20T08:05:30.012897Z","end":"2026-04-20T08:05:30.401565Z","steps":["trace[886916242] 'process raft request' (duration: 38.204333ms)","trace[886916242] 'compare' (duration: 349.720377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:30.401642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:30.012867Z","time spent":"388.740758ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:05:30.401859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.485323ms","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-20T08:05:30.401901Z","caller":"traceutil/trace.go:171","msg":"trace[1853532418] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1324; }","duration":"375.559563ms","start":"2026-04-20T08:05:30.026332Z","end":"2026-04-20T08:05:30.401892Z","steps":["trace[1853532418] 'agreement among raft nodes before linearized reading' (duration: 375.483832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:30.401931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:30.026314Z","time spent":"375.609765ms","remote":"127.0.0.1:47610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":194,"request content":"key:\"/registry/serviceaccounts/default/default\" "} {"level":"warn","ts":"2026-04-20T08:05:30.833812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.535204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:05:30.833885Z","caller":"traceutil/trace.go:171","msg":"trace[1399640488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"246.651086ms","start":"2026-04-20T08:05:30.587216Z","end":"2026-04-20T08:05:30.833867Z","steps":["trace[1399640488] 'range keys from in-memory index tree' (duration: 246.465592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:31.295411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.457862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:05:31.295518Z","caller":"traceutil/trace.go:171","msg":"trace[1989196906] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"381.908966ms","start":"2026-04-20T08:05:30.913589Z","end":"2026-04-20T08:05:31.295498Z","steps":["trace[1989196906] 'process raft request' (duration: 122.268239ms)","trace[1989196906] 'compare' (duration: 259.279529ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:31.295579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:30.913574Z","time spent":"381.979448ms","remote":"127.0.0.1:47490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:05:31.700343Z","caller":"traceutil/trace.go:171","msg":"trace[1127690447] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"140.313162ms","start":"2026-04-20T08:05:31.560006Z","end":"2026-04-20T08:05:31.70032Z","steps":["trace[1127690447] 'process raft request' (duration: 139.340271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:46.735135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.033703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:05:46.73527Z","caller":"traceutil/trace.go:171","msg":"trace[864751095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1431; }","duration":"143.227497ms","start":"2026-04-20T08:05:46.59202Z","end":"2026-04-20T08:05:46.735247Z","steps":["trace[864751095] 'range keys from in-memory index tree' (duration: 142.934301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:46.743694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.935673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.228\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T08:05:46.743803Z","caller":"traceutil/trace.go:171","msg":"trace[834375725] range","detail":"{range_begin:/registry/masterleases/199.19.213.228; range_end:; response_count:1; response_revision:1431; }","duration":"467.128558ms","start":"2026-04-20T08:05:46.276648Z","end":"2026-04-20T08:05:46.743776Z","steps":["trace[834375725] 'range keys from in-memory index tree' (duration: 457.998504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:46.743854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:46.276623Z","time spent":"467.220141ms","remote":"127.0.0.1:47454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.228\" "} {"level":"info","ts":"2026-04-20T08:05:47.230293Z","caller":"traceutil/trace.go:171","msg":"trace[156453037] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"470.842621ms","start":"2026-04-20T08:05:46.759426Z","end":"2026-04-20T08:05:47.230269Z","steps":["trace[156453037] 'read index received' (duration: 470.632436ms)","trace[156453037] 'applied index is now lower than readState.Index' (duration: 209.295µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:47.23032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:46.745131Z","time spent":"485.178471ms","remote":"127.0.0.1:47454","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-20T08:05:47.230508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.066206ms","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-20T08:05:47.230537Z","caller":"traceutil/trace.go:171","msg":"trace[1033393019] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1431; }","duration":"471.157808ms","start":"2026-04-20T08:05:46.759369Z","end":"2026-04-20T08:05:47.230527Z","steps":["trace[1033393019] 'agreement among raft nodes before linearized reading' (duration: 471.014235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:47.230561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:46.759348Z","time spent":"471.208369ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-20T08:05:47.230843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.892527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-20T08:05:47.230888Z","caller":"traceutil/trace.go:171","msg":"trace[1572550931] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1431; }","duration":"459.977308ms","start":"2026-04-20T08:05:46.770889Z","end":"2026-04-20T08:05:47.230866Z","steps":["trace[1572550931] 'agreement among raft nodes before linearized reading' (duration: 459.778554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:47.230916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:46.77088Z","time spent":"460.032199ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-20T08:05:47.230992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.734147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T08:05:47.231055Z","caller":"traceutil/trace.go:171","msg":"trace[1821560892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1431; }","duration":"211.846099ms","start":"2026-04-20T08:05:47.019199Z","end":"2026-04-20T08:05:47.231046Z","steps":["trace[1821560892] 'agreement among raft nodes before linearized reading' (duration: 211.723716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:47.231269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.336379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:05:47.231389Z","caller":"traceutil/trace.go:171","msg":"trace[1734712183] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1431; }","duration":"431.517573ms","start":"2026-04-20T08:05:46.799847Z","end":"2026-04-20T08:05:47.231365Z","steps":["trace[1734712183] 'agreement among raft nodes before linearized reading' (duration: 431.240836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:47.231454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:46.799825Z","time spent":"431.614986ms","remote":"127.0.0.1:47740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-20T08:05:47.457126Z","caller":"traceutil/trace.go:171","msg":"trace[1939540835] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"224.778287ms","start":"2026-04-20T08:05:47.232304Z","end":"2026-04-20T08:05:47.457083Z","steps":["trace[1939540835] 'process raft request' (duration: 195.868362ms)","trace[1939540835] 'compare' (duration: 28.009265ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:47.480608Z","caller":"traceutil/trace.go:171","msg":"trace[1129273738] linearizableReadLoop","detail":"{readStateIndex:1486; appliedIndex:1481; }","duration":"231.093349ms","start":"2026-04-20T08:05:47.249489Z","end":"2026-04-20T08:05:47.480582Z","steps":["trace[1129273738] 'read index received' (duration: 178.869413ms)","trace[1129273738] 'applied index is now lower than readState.Index' (duration: 52.222626ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:47.480787Z","caller":"traceutil/trace.go:171","msg":"trace[600950166] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"244.915808ms","start":"2026-04-20T08:05:47.235836Z","end":"2026-04-20T08:05:47.480752Z","steps":["trace[600950166] 'process raft request' (duration: 244.647631ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:47.481109Z","caller":"traceutil/trace.go:171","msg":"trace[1896001264] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"244.372086ms","start":"2026-04-20T08:05:47.236471Z","end":"2026-04-20T08:05:47.480843Z","steps":["trace[1896001264] 'process raft request' (duration: 244.043919ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:47.481261Z","caller":"traceutil/trace.go:171","msg":"trace[1023220385] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"245.680465ms","start":"2026-04-20T08:05:47.235564Z","end":"2026-04-20T08:05:47.481245Z","steps":["trace[1023220385] 'process raft request' (duration: 244.705573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:47.481537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.02541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:05:47.481594Z","caller":"traceutil/trace.go:171","msg":"trace[199469037] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1436; }","duration":"232.120242ms","start":"2026-04-20T08:05:47.249458Z","end":"2026-04-20T08:05:47.481578Z","steps":["trace[199469037] 'agreement among raft nodes before linearized reading' (duration: 231.885927ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:47.481733Z","caller":"traceutil/trace.go:171","msg":"trace[1909938355] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"241.340607ms","start":"2026-04-20T08:05:47.240381Z","end":"2026-04-20T08:05:47.481721Z","steps":["trace[1909938355] 'process raft request' (duration: 240.169832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:47.960797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.004807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:05:47.960923Z","caller":"traceutil/trace.go:171","msg":"trace[1682726862] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"148.559387ms","start":"2026-04-20T08:05:47.812342Z","end":"2026-04-20T08:05:47.960901Z","steps":["trace[1682726862] 'process raft request' (duration: 29.347925ms)","trace[1682726862] 'compare' (duration: 118.873194ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:48.004906Z","caller":"traceutil/trace.go:171","msg":"trace[974872839] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"190.879741ms","start":"2026-04-20T08:05:47.814008Z","end":"2026-04-20T08:05:48.004888Z","steps":["trace[974872839] 'process raft request' (duration: 190.697407ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:48.005206Z","caller":"traceutil/trace.go:171","msg":"trace[1521748072] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"189.966171ms","start":"2026-04-20T08:05:47.815202Z","end":"2026-04-20T08:05:48.005169Z","steps":["trace[1521748072] 'process raft request' (duration: 189.603383ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:48.268095Z","caller":"traceutil/trace.go:171","msg":"trace[333276557] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1490; }","duration":"200.340423ms","start":"2026-04-20T08:05:48.067728Z","end":"2026-04-20T08:05:48.268068Z","steps":["trace[333276557] 'read index received' (duration: 200.073147ms)","trace[333276557] 'applied index is now lower than readState.Index' (duration: 266.386µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:05:48.268319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.572498ms","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:524"} {"level":"info","ts":"2026-04-20T08:05:48.26841Z","caller":"traceutil/trace.go:171","msg":"trace[655285798] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1441; }","duration":"200.734571ms","start":"2026-04-20T08:05:48.067659Z","end":"2026-04-20T08:05:48.268393Z","steps":["trace[655285798] 'agreement among raft nodes before linearized reading' (duration: 200.487876ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:48.268785Z","caller":"traceutil/trace.go:171","msg":"trace[1810394086] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"272.106224ms","start":"2026-04-20T08:05:47.996005Z","end":"2026-04-20T08:05:48.268111Z","steps":["trace[1810394086] 'process raft request' (duration: 271.90259ms)"],"step_count":1}