{"level":"warn","ts":"2026-04-28T03:16:47.796394Z","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-28T03:16:47.796532Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-28T03:16:47.796641Z","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-28T03:16:47.796657Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-28T03:16:47.796676Z","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-28T03:16:47.797434Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-04-28T03:16:47.797519Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-28T03:16:48.652724Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"854.951699ms"} {"level":"info","ts":"2026-04-28T03:16:48.808432Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-04-28T03:16:48.808638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T03:16:48.80871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-04-28T03:16:48.808849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T03:16:48.808942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-04-28T03:16:48.809148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-04-28T03:16:48.964051Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T03:16:48.968983Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T03:16:48.970137Z","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-28T03:16:48.973396Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T03:16:48.97364Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T03:16:48.974548Z","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-28T03:16:48.974674Z","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-28T03:16:48.974717Z","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-28T03:16:48.975732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-04-28T03:16:48.975976Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-28T03:16:48.978759Z","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-28T03:16:48.978931Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-28T03:16:48.978983Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-28T03:16:48.979351Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T03:16:48.979434Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T03:16:49.323712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T03:16:49.323763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T03:16:49.323811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-04-28T03:16:49.323826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-04-28T03:16:49.323833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-28T03:16:49.323877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-04-28T03:16:49.323889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-28T03:16:49.325709Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T03:16:49.325715Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:16:49.325696Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:16:49.325832Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:16:49.326198Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T03:16:49.326229Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T03:16:49.326801Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:16:49.32691Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:16:49.326942Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:16:49.330127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T03:16:49.33073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"warn","ts":"2026-04-28T03:16:52.058742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843033445218071,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T03:16:52.138554Z","caller":"traceutil/trace.go:171","msg":"trace[1116608964] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"580.874575ms","start":"2026-04-28T03:16:51.55766Z","end":"2026-04-28T03:16:52.138534Z","steps":["trace[1116608964] 'read index received' (duration: 580.521003ms)","trace[1116608964] 'applied index is now lower than readState.Index' (duration: 353.052µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:52.13869Z","caller":"traceutil/trace.go:171","msg":"trace[439522325] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"582.88654ms","start":"2026-04-28T03:16:51.555772Z","end":"2026-04-28T03:16:52.138658Z","steps":["trace[439522325] 'process raft request' (duration: 582.472107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:52.138794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.116128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:16:52.138879Z","caller":"traceutil/trace.go:171","msg":"trace[1275828293] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:113; }","duration":"581.235349ms","start":"2026-04-28T03:16:51.557636Z","end":"2026-04-28T03:16:52.138871Z","steps":["trace[1275828293] 'agreement among raft nodes before linearized reading' (duration: 581.067187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:52.138911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:51.557626Z","time spent":"581.278909ms","remote":"127.0.0.1:49362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-04-28T03:16:52.138968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.172649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:16:52.139066Z","caller":"traceutil/trace.go:171","msg":"trace[1918690799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:113; }","duration":"476.336349ms","start":"2026-04-28T03:16:51.662709Z","end":"2026-04-28T03:16:52.139045Z","steps":["trace[1918690799] 'agreement among raft nodes before linearized reading' (duration: 476.168938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:52.139089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:51.555716Z","time spent":"583.03689ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:16:52.139122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:51.662664Z","time spent":"476.446181ms","remote":"127.0.0.1:48994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T03:16:52.645592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843033445218078,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:16:53.146159Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.002529688s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T03:16:53.146284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843033445218078,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:16:54.483963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337256668s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T03:16:54.484707Z","caller":"traceutil/trace.go:171","msg":"trace[65320254] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"2.339765184s","start":"2026-04-28T03:16:52.144914Z","end":"2026-04-28T03:16:54.484679Z","steps":["trace[65320254] 'read index received' (duration: 1.001476869s)","trace[65320254] 'applied index is now lower than readState.Index' (duration: 1.338285465s)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:54.484724Z","caller":"traceutil/trace.go:171","msg":"trace[949678206] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"2.341126744s","start":"2026-04-28T03:16:52.143561Z","end":"2026-04-28T03:16:54.484688Z","steps":["trace[949678206] 'process raft request' (duration: 1.002795979s)","trace[949678206] 'compare' (duration: 1.337111547s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:16:54.484775Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":5646843033445218080,"received-request-id":5646843033445218078} {"level":"warn","ts":"2026-04-28T03:16:54.484917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.821661665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T03:16:54.484908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:52.143548Z","time spent":"2.341263115s","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:16:54.484918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.339983275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:16:54.484947Z","caller":"traceutil/trace.go:171","msg":"trace[286125233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:114; }","duration":"1.821740677s","start":"2026-04-28T03:16:52.663195Z","end":"2026-04-28T03:16:54.484936Z","steps":["trace[286125233] 'agreement among raft nodes before linearized reading' (duration: 1.821673586s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:54.484969Z","caller":"traceutil/trace.go:171","msg":"trace[119791030] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:114; }","duration":"2.340060777s","start":"2026-04-28T03:16:52.144894Z","end":"2026-04-28T03:16:54.484955Z","steps":["trace[119791030] 'agreement among raft nodes before linearized reading' (duration: 2.339913366s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:54.48498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:52.663174Z","time spent":"1.821799587s","remote":"127.0.0.1:48994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T03:16:54.485004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:52.144885Z","time spent":"2.340109857s","remote":"127.0.0.1:49362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-04-28T03:16:54.485284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.440616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:16:54.485329Z","caller":"traceutil/trace.go:171","msg":"trace[1423123639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:114; }","duration":"368.519496ms","start":"2026-04-28T03:16:54.1168Z","end":"2026-04-28T03:16:54.485319Z","steps":["trace[1423123639] 'agreement among raft nodes before linearized reading' (duration: 368.460506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:54.485358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:54.116783Z","time spent":"368.568926ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:16:54.485436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.915653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:16:54.485512Z","caller":"traceutil/trace.go:171","msg":"trace[911339789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:114; }","duration":"672.044053ms","start":"2026-04-28T03:16:53.813449Z","end":"2026-04-28T03:16:54.485493Z","steps":["trace[911339789] 'agreement among raft nodes before linearized reading' (duration: 671.902152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:54.485472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.659711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T03:16:54.48555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:53.813426Z","time spent":"672.118375ms","remote":"127.0.0.1:48994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T03:16:54.485564Z","caller":"traceutil/trace.go:171","msg":"trace[673069011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:114; }","duration":"955.788472ms","start":"2026-04-28T03:16:53.529758Z","end":"2026-04-28T03:16:54.485547Z","steps":["trace[673069011] 'agreement among raft nodes before linearized reading' (duration: 955.601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:54.485602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:53.529739Z","time spent":"955.853122ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:16:54.990032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843033445218083,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T03:16:55.06845Z","caller":"traceutil/trace.go:171","msg":"trace[841238192] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"579.241301ms","start":"2026-04-28T03:16:54.489183Z","end":"2026-04-28T03:16:55.068425Z","steps":["trace[841238192] 'process raft request' (duration: 528.101247ms)","trace[841238192] 'compare' (duration: 50.958243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:16:55.068565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:54.489168Z","time spent":"579.345851ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:16:55.070893Z","caller":"traceutil/trace.go:171","msg":"trace[1270708620] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:119; }","duration":"580.895913ms","start":"2026-04-28T03:16:54.489983Z","end":"2026-04-28T03:16:55.070879Z","steps":["trace[1270708620] 'read index received' (duration: 527.315273ms)","trace[1270708620] 'applied index is now lower than readState.Index' (duration: 53.57969ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:55.070957Z","caller":"traceutil/trace.go:171","msg":"trace[963466596] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"581.190705ms","start":"2026-04-28T03:16:54.489731Z","end":"2026-04-28T03:16:55.070921Z","steps":["trace[963466596] 'process raft request' (duration: 581.087514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:55.071038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.051634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:16:55.071075Z","caller":"traceutil/trace.go:171","msg":"trace[1000330860] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:116; }","duration":"581.119524ms","start":"2026-04-28T03:16:54.489946Z","end":"2026-04-28T03:16:55.071066Z","steps":["trace[1000330860] 'agreement among raft nodes before linearized reading' (duration: 581.010693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:55.071104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:54.489936Z","time spent":"581.161714ms","remote":"127.0.0.1:49362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-04-28T03:16:55.071131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:54.489705Z","time spent":"581.347466ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:16:55.071675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.056526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:16:55.071763Z","caller":"traceutil/trace.go:171","msg":"trace[1700342262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:116; }","duration":"208.567839ms","start":"2026-04-28T03:16:54.863172Z","end":"2026-04-28T03:16:55.071739Z","steps":["trace[1700342262] 'agreement among raft nodes before linearized reading' (duration: 207.905945ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:16:55.606024Z","caller":"traceutil/trace.go:171","msg":"trace[1049719278] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"117.398339ms","start":"2026-04-28T03:16:55.4886Z","end":"2026-04-28T03:16:55.605999Z","steps":["trace[1049719278] 'process raft request' (duration: 71.870924ms)","trace[1049719278] 'compare' (duration: 45.365264ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:55.79119Z","caller":"traceutil/trace.go:171","msg":"trace[1393304304] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"120.958433ms","start":"2026-04-28T03:16:55.670198Z","end":"2026-04-28T03:16:55.791156Z","steps":["trace[1393304304] 'process raft request' (duration: 74.31529ms)","trace[1393304304] 'compare' (duration: 46.382721ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:16:57.428062Z","caller":"traceutil/trace.go:171","msg":"trace[1219438101] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"341.057549ms","start":"2026-04-28T03:16:57.086968Z","end":"2026-04-28T03:16:57.428026Z","steps":["trace[1219438101] 'process raft request' (duration: 340.915578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:57.428434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:57.086944Z","time spent":"341.23027ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:16:57.452944Z","caller":"traceutil/trace.go:171","msg":"trace[1160357469] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"364.471364ms","start":"2026-04-28T03:16:57.088442Z","end":"2026-04-28T03:16:57.452914Z","steps":["trace[1160357469] 'process raft request' (duration: 364.159742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:16:57.453096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:16:57.088416Z","time spent":"364.607335ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:17:01.695303Z","caller":"traceutil/trace.go:171","msg":"trace[39066812] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"139.78481ms","start":"2026-04-28T03:17:01.555494Z","end":"2026-04-28T03:17:01.695279Z","steps":["trace[39066812] 'read index received' (duration: 100.300246ms)","trace[39066812] 'applied index is now lower than readState.Index' (duration: 39.483284ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:01.695361Z","caller":"traceutil/trace.go:171","msg":"trace[1195529634] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"205.519423ms","start":"2026-04-28T03:17:01.489789Z","end":"2026-04-28T03:17:01.695308Z","steps":["trace[1195529634] 'process raft request' (duration: 165.950559ms)","trace[1195529634] 'compare' (duration: 39.379063ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:17:01.695464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.942481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-28T03:17:01.695503Z","caller":"traceutil/trace.go:171","msg":"trace[562486735] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:336; }","duration":"140.047002ms","start":"2026-04-28T03:17:01.55544Z","end":"2026-04-28T03:17:01.695487Z","steps":["trace[562486735] 'agreement among raft nodes before linearized reading' (duration: 139.935231ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:02.554115Z","caller":"traceutil/trace.go:171","msg":"trace[1121115185] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"120.003888ms","start":"2026-04-28T03:17:02.434088Z","end":"2026-04-28T03:17:02.554092Z","steps":["trace[1121115185] 'process raft request' (duration: 119.862016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:02.911299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.089388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:17:02.911527Z","caller":"traceutil/trace.go:171","msg":"trace[1161659811] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"127.083062ms","start":"2026-04-28T03:17:02.784406Z","end":"2026-04-28T03:17:02.911489Z","steps":["trace[1161659811] 'process raft request' (duration: 20.692062ms)","trace[1161659811] 'compare' (duration: 105.950158ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:03.113905Z","caller":"traceutil/trace.go:171","msg":"trace[243845624] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"197.383828ms","start":"2026-04-28T03:17:02.916489Z","end":"2026-04-28T03:17:03.113873Z","steps":["trace[243845624] 'read index received' (duration: 161.596501ms)","trace[243845624] 'applied index is now lower than readState.Index' (duration: 35.785707ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:03.113999Z","caller":"traceutil/trace.go:171","msg":"trace[295305388] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"198.023142ms","start":"2026-04-28T03:17:02.915928Z","end":"2026-04-28T03:17:03.113951Z","steps":["trace[295305388] 'process raft request' (duration: 162.143055ms)","trace[295305388] 'compare' (duration: 35.627796ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:17:03.114065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.54071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:17:03.114107Z","caller":"traceutil/trace.go:171","msg":"trace[1019865235] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:345; }","duration":"197.62965ms","start":"2026-04-28T03:17:02.916464Z","end":"2026-04-28T03:17:03.114093Z","steps":["trace[1019865235] 'agreement among raft nodes before linearized reading' (duration: 197.522549ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:03.11692Z","caller":"traceutil/trace.go:171","msg":"trace[844019811] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"196.01517ms","start":"2026-04-28T03:17:02.920879Z","end":"2026-04-28T03:17:03.116894Z","steps":["trace[844019811] 'process raft request' (duration: 195.859789ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:03.319649Z","caller":"traceutil/trace.go:171","msg":"trace[934763447] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"117.302576ms","start":"2026-04-28T03:17:03.202296Z","end":"2026-04-28T03:17:03.319599Z","steps":["trace[934763447] 'process raft request' (duration: 92.76109ms)","trace[934763447] 'compare' (duration: 24.291735ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:03.3199Z","caller":"traceutil/trace.go:171","msg":"trace[1616848498] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"111.329398ms","start":"2026-04-28T03:17:03.208537Z","end":"2026-04-28T03:17:03.319866Z","steps":["trace[1616848498] 'process raft request' (duration: 110.967726ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:03.671385Z","caller":"traceutil/trace.go:171","msg":"trace[586179107] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"104.955407ms","start":"2026-04-28T03:17:03.566395Z","end":"2026-04-28T03:17:03.671351Z","steps":["trace[586179107] 'process raft request' (duration: 99.350511ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:03.991988Z","caller":"traceutil/trace.go:171","msg":"trace[383465759] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:369; }","duration":"307.876028ms","start":"2026-04-28T03:17:03.684087Z","end":"2026-04-28T03:17:03.991963Z","steps":["trace[383465759] 'read index received' (duration: 263.137063ms)","trace[383465759] 'applied index is now lower than readState.Index' (duration: 44.738075ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:03.992031Z","caller":"traceutil/trace.go:171","msg":"trace[1236421558] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"314.168767ms","start":"2026-04-28T03:17:03.677839Z","end":"2026-04-28T03:17:03.992008Z","steps":["trace[1236421558] 'process raft request' (duration: 269.366202ms)","trace[1236421558] 'compare' (duration: 44.640964ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:17:03.992227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.125319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-28T03:17:03.992261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.885755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T03:17:03.992348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.926784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-28T03:17:03.99233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:03.677814Z","time spent":"314.272578ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:03.992388Z","caller":"traceutil/trace.go:171","msg":"trace[706904505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:361; }","duration":"292.033086ms","start":"2026-04-28T03:17:03.700338Z","end":"2026-04-28T03:17:03.992371Z","steps":["trace[706904505] 'agreement among raft nodes before linearized reading' (duration: 291.792665ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:03.992483Z","caller":"traceutil/trace.go:171","msg":"trace[1218581959] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:361; }","duration":"158.090334ms","start":"2026-04-28T03:17:03.834346Z","end":"2026-04-28T03:17:03.992437Z","steps":["trace[1218581959] 'agreement among raft nodes before linearized reading' (duration: 157.864383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:03.99262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.784274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-28T03:17:03.992678Z","caller":"traceutil/trace.go:171","msg":"trace[938925998] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:361; }","duration":"258.852116ms","start":"2026-04-28T03:17:03.733797Z","end":"2026-04-28T03:17:03.992649Z","steps":["trace[938925998] 'agreement among raft nodes before linearized reading' (duration: 258.783085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:03.992734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.505503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T03:17:03.992304Z","caller":"traceutil/trace.go:171","msg":"trace[1162052906] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:361; }","duration":"308.30553ms","start":"2026-04-28T03:17:03.683976Z","end":"2026-04-28T03:17:03.992282Z","steps":["trace[1162052906] 'agreement among raft nodes before linearized reading' (duration: 308.088889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:03.992773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.192373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-28T03:17:03.992787Z","caller":"traceutil/trace.go:171","msg":"trace[207665205] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:361; }","duration":"107.597003ms","start":"2026-04-28T03:17:03.885174Z","end":"2026-04-28T03:17:03.992771Z","steps":["trace[207665205] 'agreement among raft nodes before linearized reading' (duration: 107.516763ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:03.992797Z","caller":"traceutil/trace.go:171","msg":"trace[613744909] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:361; }","duration":"208.267423ms","start":"2026-04-28T03:17:03.784522Z","end":"2026-04-28T03:17:03.992789Z","steps":["trace[613744909] 'agreement among raft nodes before linearized reading' (duration: 208.223483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:03.992806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:03.683964Z","time spent":"308.824363ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-04-28T03:17:04.189143Z","caller":"traceutil/trace.go:171","msg":"trace[983206811] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"189.81938ms","start":"2026-04-28T03:17:03.999298Z","end":"2026-04-28T03:17:04.189117Z","steps":["trace[983206811] 'process raft request' (duration: 189.573599ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.189367Z","caller":"traceutil/trace.go:171","msg":"trace[403237442] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"187.737457ms","start":"2026-04-28T03:17:04.001612Z","end":"2026-04-28T03:17:04.18935Z","steps":["trace[403237442] 'process raft request' (duration: 187.577356ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.189424Z","caller":"traceutil/trace.go:171","msg":"trace[1297228191] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"189.023524ms","start":"2026-04-28T03:17:04.000382Z","end":"2026-04-28T03:17:04.189405Z","steps":["trace[1297228191] 'process raft request' (duration: 188.705952ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.189477Z","caller":"traceutil/trace.go:171","msg":"trace[2005009971] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"188.31478ms","start":"2026-04-28T03:17:04.001129Z","end":"2026-04-28T03:17:04.189444Z","steps":["trace[2005009971] 'process raft request' (duration: 188.001778ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.189696Z","caller":"traceutil/trace.go:171","msg":"trace[732861391] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"185.726763ms","start":"2026-04-28T03:17:04.003958Z","end":"2026-04-28T03:17:04.189685Z","steps":["trace[732861391] 'process raft request' (duration: 185.32656ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.18919Z","caller":"traceutil/trace.go:171","msg":"trace[1762681327] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"189.033304ms","start":"2026-04-28T03:17:04.000123Z","end":"2026-04-28T03:17:04.189157Z","steps":["trace[1762681327] 'process raft request' (duration: 188.899934ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.191105Z","caller":"traceutil/trace.go:171","msg":"trace[1301658738] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"185.094359ms","start":"2026-04-28T03:17:04.005966Z","end":"2026-04-28T03:17:04.191061Z","steps":["trace[1301658738] 'process raft request' (duration: 183.659179ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.192452Z","caller":"traceutil/trace.go:171","msg":"trace[187083910] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:371; }","duration":"158.194349ms","start":"2026-04-28T03:17:04.034207Z","end":"2026-04-28T03:17:04.192402Z","steps":["trace[187083910] 'read index received' (duration: 40.690616ms)","trace[187083910] 'applied index is now lower than readState.Index' (duration: 117.412372ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:04.192518Z","caller":"traceutil/trace.go:171","msg":"trace[390058818] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"186.439397ms","start":"2026-04-28T03:17:04.006051Z","end":"2026-04-28T03:17:04.19249Z","steps":["trace[390058818] 'process raft request' (duration: 183.859731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:04.193473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.950278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-28T03:17:04.193522Z","caller":"traceutil/trace.go:171","msg":"trace[1701604204] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:371; }","duration":"109.012818ms","start":"2026-04-28T03:17:04.084491Z","end":"2026-04-28T03:17:04.193504Z","steps":["trace[1701604204] 'agreement among raft nodes before linearized reading' (duration: 108.575396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:04.193524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.299216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-28T03:17:04.193587Z","caller":"traceutil/trace.go:171","msg":"trace[880219114] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:371; }","duration":"159.403107ms","start":"2026-04-28T03:17:04.034164Z","end":"2026-04-28T03:17:04.193567Z","steps":["trace[880219114] 'agreement among raft nodes before linearized reading' (duration: 158.579081ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.194121Z","caller":"traceutil/trace.go:171","msg":"trace[1040995041] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"186.670229ms","start":"2026-04-28T03:17:04.006061Z","end":"2026-04-28T03:17:04.192731Z","steps":["trace[1040995041] 'process raft request' (duration: 186.128745ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.413207Z","caller":"traceutil/trace.go:171","msg":"trace[483738114] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"214.129983ms","start":"2026-04-28T03:17:04.199034Z","end":"2026-04-28T03:17:04.413164Z","steps":["trace[483738114] 'process raft request' (duration: 191.826702ms)","trace[483738114] 'compare' (duration: 22.084569ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:04.448895Z","caller":"traceutil/trace.go:171","msg":"trace[1633114174] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"244.930818ms","start":"2026-04-28T03:17:04.203935Z","end":"2026-04-28T03:17:04.448866Z","steps":["trace[1633114174] 'process raft request' (duration: 244.833088ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.448907Z","caller":"traceutil/trace.go:171","msg":"trace[1369019908] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"245.2962ms","start":"2026-04-28T03:17:04.203577Z","end":"2026-04-28T03:17:04.448873Z","steps":["trace[1369019908] 'process raft request' (duration: 245.056019ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.449314Z","caller":"traceutil/trace.go:171","msg":"trace[1256255050] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"232.543199ms","start":"2026-04-28T03:17:04.21673Z","end":"2026-04-28T03:17:04.449273Z","steps":["trace[1256255050] 'process raft request' (duration: 232.084436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.449353Z","caller":"traceutil/trace.go:171","msg":"trace[1496130940] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"245.198288ms","start":"2026-04-28T03:17:04.204136Z","end":"2026-04-28T03:17:04.449334Z","steps":["trace[1496130940] 'process raft request' (duration: 245.073577ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.449537Z","caller":"traceutil/trace.go:171","msg":"trace[798632291] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:380; }","duration":"245.500331ms","start":"2026-04-28T03:17:04.204024Z","end":"2026-04-28T03:17:04.449524Z","steps":["trace[798632291] 'read index received' (duration: 186.938181ms)","trace[798632291] 'applied index is now lower than readState.Index' (duration: 58.56171ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:04.449556Z","caller":"traceutil/trace.go:171","msg":"trace[489506281] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"232.521459ms","start":"2026-04-28T03:17:04.216983Z","end":"2026-04-28T03:17:04.449505Z","steps":["trace[489506281] 'process raft request' (duration: 232.269028ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.449616Z","caller":"traceutil/trace.go:171","msg":"trace[1887179902] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"232.503098ms","start":"2026-04-28T03:17:04.217028Z","end":"2026-04-28T03:17:04.449531Z","steps":["trace[1887179902] 'process raft request' (duration: 232.278637ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.449808Z","caller":"traceutil/trace.go:171","msg":"trace[1698407923] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"232.63091ms","start":"2026-04-28T03:17:04.21717Z","end":"2026-04-28T03:17:04.449801Z","steps":["trace[1698407923] 'process raft request' (duration: 232.229318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:04.449668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.626232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T03:17:04.449977Z","caller":"traceutil/trace.go:171","msg":"trace[196878524] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:380; }","duration":"245.964674ms","start":"2026-04-28T03:17:04.203998Z","end":"2026-04-28T03:17:04.449962Z","steps":["trace[196878524] 'agreement among raft nodes before linearized reading' (duration: 245.563902ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.449896Z","caller":"traceutil/trace.go:171","msg":"trace[847226252] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"232.73875ms","start":"2026-04-28T03:17:04.217147Z","end":"2026-04-28T03:17:04.449885Z","steps":["trace[847226252] 'process raft request' (duration: 232.190747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:04.648448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.151766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:17:04.648704Z","caller":"traceutil/trace.go:171","msg":"trace[549413986] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:389; }","duration":"192.252864ms","start":"2026-04-28T03:17:04.456434Z","end":"2026-04-28T03:17:04.648687Z","steps":["trace[549413986] 'read index received' (duration: 3.14528ms)","trace[549413986] 'applied index is now lower than readState.Index' (duration: 189.106484ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:04.6488Z","caller":"traceutil/trace.go:171","msg":"trace[292418080] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"191.157937ms","start":"2026-04-28T03:17:04.457613Z","end":"2026-04-28T03:17:04.648771Z","steps":["trace[292418080] 'process raft request' (duration: 190.959336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:04.648851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.409115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-28T03:17:04.64888Z","caller":"traceutil/trace.go:171","msg":"trace[2065581764] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:384; }","duration":"192.475545ms","start":"2026-04-28T03:17:04.456395Z","end":"2026-04-28T03:17:04.648871Z","steps":["trace[2065581764] 'agreement among raft nodes before linearized reading' (duration: 192.388815ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:04.649Z","caller":"traceutil/trace.go:171","msg":"trace[1618785375] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"193.519092ms","start":"2026-04-28T03:17:04.455469Z","end":"2026-04-28T03:17:04.648988Z","steps":["trace[1618785375] 'process raft request' (duration: 85.750992ms)","trace[1618785375] 'compare' (duration: 106.895245ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:04.649271Z","caller":"traceutil/trace.go:171","msg":"trace[246415952] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"189.931829ms","start":"2026-04-28T03:17:04.459258Z","end":"2026-04-28T03:17:04.64919Z","steps":["trace[246415952] 'process raft request' (duration: 189.382036ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:05.095988Z","caller":"traceutil/trace.go:171","msg":"trace[831746590] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"252.24197ms","start":"2026-04-28T03:17:04.843725Z","end":"2026-04-28T03:17:05.095967Z","steps":["trace[831746590] 'process raft request' (duration: 191.718559ms)","trace[831746590] 'compare' (duration: 60.42861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:17:05.463487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.374532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:17:05.463732Z","caller":"traceutil/trace.go:171","msg":"trace[1216610031] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"278.977621ms","start":"2026-04-28T03:17:05.184678Z","end":"2026-04-28T03:17:05.463655Z","steps":["trace[1216610031] 'process raft request' (duration: 106.369248ms)","trace[1216610031] 'compare' (duration: 172.11873ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:05.463822Z","caller":"traceutil/trace.go:171","msg":"trace[805584402] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"279.067172ms","start":"2026-04-28T03:17:05.184736Z","end":"2026-04-28T03:17:05.463803Z","steps":["trace[805584402] 'process raft request' (duration: 278.822661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:05.858076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.657807ms","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-28T03:17:05.858175Z","caller":"traceutil/trace.go:171","msg":"trace[2050889613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"276.800169ms","start":"2026-04-28T03:17:05.581354Z","end":"2026-04-28T03:17:05.858154Z","steps":["trace[2050889613] 'range keys from in-memory index tree' (duration: 276.545697ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:20.328636Z","caller":"traceutil/trace.go:171","msg":"trace[870907006] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"177.442444ms","start":"2026-04-28T03:17:20.151167Z","end":"2026-04-28T03:17:20.32861Z","steps":["trace[870907006] 'process raft request' (duration: 177.051951ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:24.238647Z","caller":"traceutil/trace.go:171","msg":"trace[1088591040] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"105.496563ms","start":"2026-04-28T03:17:24.133127Z","end":"2026-04-28T03:17:24.238624Z","steps":["trace[1088591040] 'read index received' (duration: 105.337086ms)","trace[1088591040] 'applied index is now lower than readState.Index' (duration: 158.667µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:24.238706Z","caller":"traceutil/trace.go:171","msg":"trace[891229988] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"217.116991ms","start":"2026-04-28T03:17:24.021571Z","end":"2026-04-28T03:17:24.238688Z","steps":["trace[891229988] 'process raft request' (duration: 216.926693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:24.238836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.684551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-04-28T03:17:24.238876Z","caller":"traceutil/trace.go:171","msg":"trace[1254865662] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:480; }","duration":"105.770824ms","start":"2026-04-28T03:17:24.133094Z","end":"2026-04-28T03:17:24.238865Z","steps":["trace[1254865662] 'agreement among raft nodes before linearized reading' (duration: 105.657319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:24.461058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.715716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T03:17:24.461077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.510469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-04-28T03:17:24.461151Z","caller":"traceutil/trace.go:171","msg":"trace[112114221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:480; }","duration":"107.847752ms","start":"2026-04-28T03:17:24.353282Z","end":"2026-04-28T03:17:24.461129Z","steps":["trace[112114221] 'range keys from in-memory index tree' (duration: 107.621512ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:24.46116Z","caller":"traceutil/trace.go:171","msg":"trace[1172946753] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:480; }","duration":"101.622774ms","start":"2026-04-28T03:17:24.35952Z","end":"2026-04-28T03:17:24.461143Z","steps":["trace[1172946753] 'range keys from in-memory index tree' (duration: 101.440567ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:26.634313Z","caller":"traceutil/trace.go:171","msg":"trace[315075358] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"159.936706ms","start":"2026-04-28T03:17:26.47435Z","end":"2026-04-28T03:17:26.634287Z","steps":["trace[315075358] 'process raft request' (duration: 159.651124ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:27.490814Z","caller":"traceutil/trace.go:171","msg":"trace[1919116893] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"555.88724ms","start":"2026-04-28T03:17:26.9349Z","end":"2026-04-28T03:17:27.490788Z","steps":["trace[1919116893] 'process raft request' (duration: 555.727914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:27.491025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.934881Z","time spent":"556.075858ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:27.500449Z","caller":"traceutil/trace.go:171","msg":"trace[1397795058] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"562.190951ms","start":"2026-04-28T03:17:26.938237Z","end":"2026-04-28T03:17:27.500428Z","steps":["trace[1397795058] 'process raft request' (duration: 562.076337ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:27.500536Z","caller":"traceutil/trace.go:171","msg":"trace[1697113545] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"561.746074ms","start":"2026-04-28T03:17:26.938762Z","end":"2026-04-28T03:17:27.500508Z","steps":["trace[1697113545] 'process raft request' (duration: 561.617419ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:27.500607Z","caller":"traceutil/trace.go:171","msg":"trace[270519276] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"554.909627ms","start":"2026-04-28T03:17:26.945687Z","end":"2026-04-28T03:17:27.500597Z","steps":["trace[270519276] 'process raft request' (duration: 554.800853ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:27.500621Z","caller":"traceutil/trace.go:171","msg":"trace[584202268] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"555.372017ms","start":"2026-04-28T03:17:26.94516Z","end":"2026-04-28T03:17:27.500532Z","steps":["trace[584202268] 'process raft request' (duration: 555.251352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:27.500671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.945681Z","time spent":"554.957619ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:17:27.500667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.938751Z","time spent":"561.856948ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:17:27.500558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.938213Z","time spent":"562.296736ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:17:27.500816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.945142Z","time spent":"555.568005ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:27.500865Z","caller":"traceutil/trace.go:171","msg":"trace[408910042] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"549.980818ms","start":"2026-04-28T03:17:26.950875Z","end":"2026-04-28T03:17:27.500856Z","steps":["trace[408910042] 'process raft request' (duration: 549.645775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:27.50091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.950869Z","time spent":"550.02134ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:27.500981Z","caller":"traceutil/trace.go:171","msg":"trace[1118430255] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"545.425914ms","start":"2026-04-28T03:17:26.955548Z","end":"2026-04-28T03:17:27.500974Z","steps":["trace[1118430255] 'process raft request' (duration: 545.244817ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:27.500941Z","caller":"traceutil/trace.go:171","msg":"trace[1164450816] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"548.529119ms","start":"2026-04-28T03:17:26.952397Z","end":"2026-04-28T03:17:27.500927Z","steps":["trace[1164450816] 'process raft request' (duration: 548.169395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:27.501039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.955335Z","time spent":"545.674234ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:17:27.501078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.952389Z","time spent":"548.662204ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:27.501166Z","caller":"traceutil/trace.go:171","msg":"trace[1133056714] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"522.982478ms","start":"2026-04-28T03:17:26.978173Z","end":"2026-04-28T03:17:27.501156Z","steps":["trace[1133056714] 'process raft request' (duration: 522.671616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:27.501233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.978157Z","time spent":"523.054721ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:27.501343Z","caller":"traceutil/trace.go:171","msg":"trace[195654408] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"515.988465ms","start":"2026-04-28T03:17:26.985348Z","end":"2026-04-28T03:17:27.501336Z","steps":["trace[195654408] 'process raft request' (duration: 515.60371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:27.501387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:26.985322Z","time spent":"516.050048ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:27.501466Z","caller":"traceutil/trace.go:171","msg":"trace[1376268496] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"478.895217ms","start":"2026-04-28T03:17:27.022559Z","end":"2026-04-28T03:17:27.501454Z","steps":["trace[1376268496] 'process raft request' (duration: 478.637677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:27.501598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:27.02254Z","time spent":"478.995551ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:27.503287Z","caller":"traceutil/trace.go:171","msg":"trace[2000266280] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"387.083057ms","start":"2026-04-28T03:17:27.11619Z","end":"2026-04-28T03:17:27.503273Z","steps":["trace[2000266280] 'process raft request' (duration: 385.286605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:27.50334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:27.116127Z","time spent":"387.189761ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:17:28.75336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843033445218987,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:17:28.75831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.269365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:17:28.758628Z","caller":"traceutil/trace.go:171","msg":"trace[1341639156] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"744.292203ms","start":"2026-04-28T03:17:28.014318Z","end":"2026-04-28T03:17:28.758611Z","steps":["trace[1341639156] 'process raft request' (duration: 744.116856ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:28.758641Z","caller":"traceutil/trace.go:171","msg":"trace[1702401492] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"767.005404ms","start":"2026-04-28T03:17:27.991554Z","end":"2026-04-28T03:17:28.758559Z","steps":["trace[1702401492] 'process raft request' (duration: 43.400045ms)","trace[1702401492] 'compare' (duration: 723.12188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:17:28.758703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:28.014294Z","time spent":"744.377836ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:17:28.758822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:27.991536Z","time spent":"767.192091ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:17:29.421205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.195252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:17:29.42156Z","caller":"traceutil/trace.go:171","msg":"trace[1565831465] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"649.038372ms","start":"2026-04-28T03:17:28.772501Z","end":"2026-04-28T03:17:29.421539Z","steps":["trace[1565831465] 'process raft request' (duration: 648.923107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:29.421679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:28.77248Z","time spent":"649.142876ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:17:29.421843Z","caller":"traceutil/trace.go:171","msg":"trace[844833553] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.262578924s","start":"2026-04-28T03:17:28.159242Z","end":"2026-04-28T03:17:29.421821Z","steps":["trace[844833553] 'process raft request' (duration: 857.701385ms)","trace[844833553] 'compare' (duration: 403.577158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:17:29.421942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:28.159219Z","time spent":"1.262680818s","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:29.422003Z","caller":"traceutil/trace.go:171","msg":"trace[419901603] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"914.44928ms","start":"2026-04-28T03:17:28.50751Z","end":"2026-04-28T03:17:29.42196Z","steps":["trace[419901603] 'process raft request' (duration: 913.813194ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:29.422044Z","caller":"traceutil/trace.go:171","msg":"trace[319062610] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"1.169549539s","start":"2026-04-28T03:17:28.252451Z","end":"2026-04-28T03:17:29.422Z","steps":["trace[319062610] 'read index received' (duration: 764.332577ms)","trace[319062610] 'applied index is now lower than readState.Index' (duration: 405.213732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:17:29.422176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:28.50748Z","time spent":"914.605066ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:17:29.422208Z","caller":"traceutil/trace.go:171","msg":"trace[1633234468] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"637.594552ms","start":"2026-04-28T03:17:28.784601Z","end":"2026-04-28T03:17:29.422196Z","steps":["trace[1633234468] 'process raft request' (duration: 636.886884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:29.422353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:28.784584Z","time spent":"637.659644ms","remote":"127.0.0.1:49056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20842,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:17:29.422375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.98954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T03:17:29.422429Z","caller":"traceutil/trace.go:171","msg":"trace[1745259657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"783.066323ms","start":"2026-04-28T03:17:28.639347Z","end":"2026-04-28T03:17:29.422413Z","steps":["trace[1745259657] 'agreement among raft nodes before linearized reading' (duration: 782.908007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:29.422475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:28.639331Z","time spent":"783.134625ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:17:29.422662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170213225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:17:29.422716Z","caller":"traceutil/trace.go:171","msg":"trace[894218499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"1.170312669s","start":"2026-04-28T03:17:28.252393Z","end":"2026-04-28T03:17:29.422705Z","steps":["trace[894218499] 'agreement among raft nodes before linearized reading' (duration: 1.170125011s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:29.422748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:28.252369Z","time spent":"1.170371941s","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T03:17:35.476725Z","caller":"traceutil/trace.go:171","msg":"trace[1099056886] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"447.976923ms","start":"2026-04-28T03:17:35.028722Z","end":"2026-04-28T03:17:35.476699Z","steps":["trace[1099056886] 'process raft request' (duration: 447.820917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:35.476904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:35.028704Z","time spent":"448.113088ms","remote":"127.0.0.1:49200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:17:36.163426Z","caller":"traceutil/trace.go:171","msg":"trace[148594964] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"717.204852ms","start":"2026-04-28T03:17:35.446193Z","end":"2026-04-28T03:17:36.163398Z","steps":["trace[148594964] 'read index received' (duration: 30.473225ms)","trace[148594964] 'applied index is now lower than readState.Index' (duration: 686.730507ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:36.163764Z","caller":"traceutil/trace.go:171","msg":"trace[416918955] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.012322515s","start":"2026-04-28T03:17:35.151427Z","end":"2026-04-28T03:17:36.163749Z","steps":["trace[416918955] 'process raft request' (duration: 1.011438684s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:36.164025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:35.151401Z","time spent":"1.012535293s","remote":"127.0.0.1:49114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:17:36.164061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.510581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T03:17:36.164083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.882125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:17:36.164101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.810213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T03:17:36.164186Z","caller":"traceutil/trace.go:171","msg":"trace[1780886602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"441.632205ms","start":"2026-04-28T03:17:35.722504Z","end":"2026-04-28T03:17:36.164136Z","steps":["trace[1780886602] 'agreement among raft nodes before linearized reading' (duration: 441.420777ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:36.16421Z","caller":"traceutil/trace.go:171","msg":"trace[1739014100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"718.079013ms","start":"2026-04-28T03:17:35.446105Z","end":"2026-04-28T03:17:36.164184Z","steps":["trace[1739014100] 'agreement among raft nodes before linearized reading' (duration: 717.883336ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:36.164221Z","caller":"traceutil/trace.go:171","msg":"trace[1571404657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"701.984009ms","start":"2026-04-28T03:17:35.462215Z","end":"2026-04-28T03:17:36.164199Z","steps":["trace[1571404657] 'agreement among raft nodes before linearized reading' (duration: 701.592995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:36.164235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:35.722483Z","time spent":"441.742609ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T03:17:36.16427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:35.462182Z","time spent":"702.079262ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:17:36.164267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:35.44608Z","time spent":"718.178006ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T03:17:36.16428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.982319ms","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-28T03:17:36.164338Z","caller":"traceutil/trace.go:171","msg":"trace[173195463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"702.104624ms","start":"2026-04-28T03:17:35.462216Z","end":"2026-04-28T03:17:36.164321Z","steps":["trace[173195463] 'agreement among raft nodes before linearized reading' (duration: 701.878166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:36.164045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.707184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T03:17:36.164385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:35.462181Z","time spent":"702.195057ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T03:17:36.164401Z","caller":"traceutil/trace.go:171","msg":"trace[218119490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"342.115289ms","start":"2026-04-28T03:17:35.822276Z","end":"2026-04-28T03:17:36.164391Z","steps":["trace[218119490] 'agreement among raft nodes before linearized reading' (duration: 341.56505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:36.16443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:35.822259Z","time spent":"342.163571ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T03:17:37.009422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843033445219189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:17:37.510005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843033445219189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:17:37.968772Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.789754512s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T03:17:38.508833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000081523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T03:17:38.508903Z","caller":"traceutil/trace.go:171","msg":"trace[1953344548] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000208328s","start":"2026-04-28T03:17:36.508681Z","end":"2026-04-28T03:17:38.50889Z","steps":["trace[1953344548] 'agreement among raft nodes before linearized reading' (duration: 2.000113345s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:38.508935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:36.50866Z","time spent":"2.00026848s","remote":"127.0.0.1:48994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/28 03:17:38 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T03:17:38.977544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.876521662s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:17:38.977861Z","caller":"traceutil/trace.go:171","msg":"trace[1682782379] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:633; }","duration":"1.876856404s","start":"2026-04-28T03:17:37.100963Z","end":"2026-04-28T03:17:38.977819Z","steps":["trace[1682782379] 'range keys from in-memory index tree' (duration: 1.876392776s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:38.977914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008786757s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:17:38.978318Z","caller":"traceutil/trace.go:171","msg":"trace[1382247917] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"2.80800309s","start":"2026-04-28T03:17:36.170274Z","end":"2026-04-28T03:17:38.978277Z","steps":["trace[1382247917] 'process raft request' (duration: 1.798713335s)","trace[1382247917] 'compare' (duration: 1.008477356s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:17:38.978446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:36.170242Z","time spent":"2.808144394s","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:17:38.978496Z","caller":"traceutil/trace.go:171","msg":"trace[1639601580] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:653; }","duration":"2.469722604s","start":"2026-04-28T03:17:36.508755Z","end":"2026-04-28T03:17:38.978478Z","steps":["trace[1639601580] 'read index received' (duration: 1.460239593s)","trace[1639601580] 'applied index is now lower than readState.Index' (duration: 1.009481641s)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:17:38.978626Z","caller":"traceutil/trace.go:171","msg":"trace[93720665] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.798331913s","start":"2026-04-28T03:17:36.18028Z","end":"2026-04-28T03:17:38.978611Z","steps":["trace[93720665] 'process raft request' (duration: 2.798086185s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:38.978635Z","caller":"traceutil/trace.go:171","msg":"trace[834436691] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"2.807698938s","start":"2026-04-28T03:17:36.170908Z","end":"2026-04-28T03:17:38.978607Z","steps":["trace[834436691] 'process raft request' (duration: 2.807403288s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:38.978728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:36.180267Z","time spent":"2.798403525s","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:17:38.978793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:36.170894Z","time spent":"2.807823832s","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:17:38.979013Z","caller":"traceutil/trace.go:171","msg":"trace[2052519412] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.808634731s","start":"2026-04-28T03:17:36.170324Z","end":"2026-04-28T03:17:38.978959Z","steps":["trace[2052519412] 'process raft request' (duration: 2.807806262s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:38.97906Z","caller":"traceutil/trace.go:171","msg":"trace[409993129] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"2.808905281s","start":"2026-04-28T03:17:36.170117Z","end":"2026-04-28T03:17:38.979022Z","steps":["trace[409993129] 'process raft request' (duration: 2.808119424s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:38.979204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.516255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:17:38.979284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:36.170301Z","time spent":"2.808833908s","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:17:38.97905Z","caller":"traceutil/trace.go:171","msg":"trace[1186200230] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.75779409s","start":"2026-04-28T03:17:36.221238Z","end":"2026-04-28T03:17:38.979032Z","steps":["trace[1186200230] 'process raft request' (duration: 2.757189399s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:38.979733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:36.221208Z","time spent":"2.758456603s","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:17:38.979304Z","caller":"traceutil/trace.go:171","msg":"trace[1205616806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"469.592188ms","start":"2026-04-28T03:17:38.509651Z","end":"2026-04-28T03:17:38.979243Z","steps":["trace[1205616806] 'agreement among raft nodes before linearized reading' (duration: 469.501645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:38.979903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:38.509636Z","time spent":"470.248231ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T03:17:38.979307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:36.170096Z","time spent":"2.80913639s","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:17:40.712723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.746889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:17:40.712835Z","caller":"traceutil/trace.go:171","msg":"trace[2025293192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"266.915004ms","start":"2026-04-28T03:17:40.445897Z","end":"2026-04-28T03:17:40.712812Z","steps":["trace[2025293192] 'range keys from in-memory index tree' (duration: 266.578343ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:54.221582Z","caller":"traceutil/trace.go:171","msg":"trace[1649570457] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"101.649081ms","start":"2026-04-28T03:17:54.119906Z","end":"2026-04-28T03:17:54.221555Z","steps":["trace[1649570457] 'process raft request' (duration: 101.505456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:55.815312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.331934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-28T03:17:55.815683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.78921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-dxl2k\" ","response":"range_response_count:1 size:5050"} {"level":"info","ts":"2026-04-28T03:17:55.815742Z","caller":"traceutil/trace.go:171","msg":"trace[582269292] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-dxl2k; range_end:; response_count:1; response_revision:793; }","duration":"718.883603ms","start":"2026-04-28T03:17:55.096842Z","end":"2026-04-28T03:17:55.815726Z","steps":["trace[582269292] 'range keys from in-memory index tree' (duration: 717.773242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:55.815792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:55.096823Z","time spent":"718.948115ms","remote":"127.0.0.1:49200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5072,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-dxl2k\" "} {"level":"warn","ts":"2026-04-28T03:17:55.815869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.26325ms","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-28T03:17:55.815949Z","caller":"traceutil/trace.go:171","msg":"trace[431719164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"742.383313ms","start":"2026-04-28T03:17:55.073548Z","end":"2026-04-28T03:17:55.815932Z","steps":["trace[431719164] 'range keys from in-memory index tree' (duration: 742.107636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:55.815995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:55.073538Z","time spent":"742.447345ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T03:17:55.815684Z","caller":"traceutil/trace.go:171","msg":"trace[1273450183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"758.741676ms","start":"2026-04-28T03:17:55.056916Z","end":"2026-04-28T03:17:55.815658Z","steps":["trace[1273450183] 'range keys from in-memory index tree' (duration: 758.17061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:55.816287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:55.056896Z","time spent":"759.370294ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:17:55.815428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.146141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-28T03:17:55.816468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.24746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:17:55.816536Z","caller":"traceutil/trace.go:171","msg":"trace[89189844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"760.398004ms","start":"2026-04-28T03:17:55.056125Z","end":"2026-04-28T03:17:55.816523Z","steps":["trace[89189844] 'range keys from in-memory index tree' (duration: 760.079214ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:55.816547Z","caller":"traceutil/trace.go:171","msg":"trace[1717753604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"757.288523ms","start":"2026-04-28T03:17:55.059232Z","end":"2026-04-28T03:17:55.816521Z","steps":["trace[1717753604] 'range keys from in-memory index tree' (duration: 756.000207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:55.816566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:55.056101Z","time spent":"760.457996ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:17:55.816606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:55.05921Z","time spent":"757.382957ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T03:17:55.815585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.887892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T03:17:55.818514Z","caller":"traceutil/trace.go:171","msg":"trace[1174861960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"758.062687ms","start":"2026-04-28T03:17:55.058655Z","end":"2026-04-28T03:17:55.816718Z","steps":["trace[1174861960] 'range keys from in-memory index tree' (duration: 756.79482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:55.81856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:55.058646Z","time spent":"759.899909ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T03:17:56.028446Z","caller":"traceutil/trace.go:171","msg":"trace[1942386174] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"311.534219ms","start":"2026-04-28T03:17:55.716888Z","end":"2026-04-28T03:17:56.028422Z","steps":["trace[1942386174] 'read index received' (duration: 311.507168ms)","trace[1942386174] 'applied index is now lower than readState.Index' (duration: 26.031µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:17:56.028604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.683053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:17:56.028638Z","caller":"traceutil/trace.go:171","msg":"trace[1119131412] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:794; }","duration":"311.773786ms","start":"2026-04-28T03:17:55.716853Z","end":"2026-04-28T03:17:56.028627Z","steps":["trace[1119131412] 'agreement among raft nodes before linearized reading' (duration: 311.666153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:56.028681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:55.716833Z","time spent":"311.838287ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-04-28T03:17:56.028901Z","caller":"traceutil/trace.go:171","msg":"trace[1931993171] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"797.261261ms","start":"2026-04-28T03:17:55.231536Z","end":"2026-04-28T03:17:56.028797Z","steps":["trace[1931993171] 'process raft request' (duration: 796.709255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:17:56.029271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:17:55.231497Z","time spent":"797.668643ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:17:56.037656Z","caller":"traceutil/trace.go:171","msg":"trace[1298131839] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"214.49281ms","start":"2026-04-28T03:17:55.823138Z","end":"2026-04-28T03:17:56.037631Z","steps":["trace[1298131839] 'process raft request' (duration: 214.269284ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:56.037889Z","caller":"traceutil/trace.go:171","msg":"trace[2048093633] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"212.833141ms","start":"2026-04-28T03:17:55.825039Z","end":"2026-04-28T03:17:56.037872Z","steps":["trace[2048093633] 'process raft request' (duration: 212.468701ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:56.038085Z","caller":"traceutil/trace.go:171","msg":"trace[1751770555] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"212.665448ms","start":"2026-04-28T03:17:55.825409Z","end":"2026-04-28T03:17:56.038075Z","steps":["trace[1751770555] 'process raft request' (duration: 212.151062ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:56.03843Z","caller":"traceutil/trace.go:171","msg":"trace[1684497480] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"212.619856ms","start":"2026-04-28T03:17:55.825798Z","end":"2026-04-28T03:17:56.038418Z","steps":["trace[1684497480] 'process raft request' (duration: 212.537444ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:56.038431Z","caller":"traceutil/trace.go:171","msg":"trace[1389194434] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"212.657927ms","start":"2026-04-28T03:17:55.825757Z","end":"2026-04-28T03:17:56.038415Z","steps":["trace[1389194434] 'process raft request' (duration: 212.168312ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:17:56.0385Z","caller":"traceutil/trace.go:171","msg":"trace[1469018335] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"212.575444ms","start":"2026-04-28T03:17:55.825914Z","end":"2026-04-28T03:17:56.038489Z","steps":["trace[1469018335] 'process raft request' (duration: 212.446091ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:00.058006Z","caller":"traceutil/trace.go:171","msg":"trace[1604703588] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"175.437675ms","start":"2026-04-28T03:17:59.88254Z","end":"2026-04-28T03:18:00.057978Z","steps":["trace[1604703588] 'process raft request' (duration: 175.204768ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:05.215431Z","caller":"traceutil/trace.go:171","msg":"trace[916118459] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"120.372433ms","start":"2026-04-28T03:18:05.095039Z","end":"2026-04-28T03:18:05.215412Z","steps":["trace[916118459] 'process raft request' (duration: 120.2532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:05.671004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.683518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:05.671079Z","caller":"traceutil/trace.go:171","msg":"trace[2107856585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"224.797311ms","start":"2026-04-28T03:18:05.446262Z","end":"2026-04-28T03:18:05.671059Z","steps":["trace[2107856585] 'range keys from in-memory index tree' (duration: 224.532673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:06.63386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.606372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:18:06.634065Z","caller":"traceutil/trace.go:171","msg":"trace[1488076833] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"389.594647ms","start":"2026-04-28T03:18:06.244453Z","end":"2026-04-28T03:18:06.634048Z","steps":["trace[1488076833] 'process raft request' (duration: 389.509335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:06.634149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:06.244443Z","time spent":"389.665619ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:18:06.634245Z","caller":"traceutil/trace.go:171","msg":"trace[400641086] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"397.230804ms","start":"2026-04-28T03:18:06.236987Z","end":"2026-04-28T03:18:06.634218Z","steps":["trace[400641086] 'process raft request' (duration: 223.18826ms)","trace[400641086] 'compare' (duration: 173.49506ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:18:06.634356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:06.236958Z","time spent":"397.348207ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1669,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:18:06.85013Z","caller":"traceutil/trace.go:171","msg":"trace[252313247] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"206.339026ms","start":"2026-04-28T03:18:06.643763Z","end":"2026-04-28T03:18:06.850102Z","steps":["trace[252313247] 'process raft request' (duration: 193.590409ms)","trace[252313247] 'compare' (duration: 12.620634ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:19:00.791278Z","caller":"traceutil/trace.go:171","msg":"trace[334343034] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"162.784112ms","start":"2026-04-28T03:19:00.628426Z","end":"2026-04-28T03:19:00.79121Z","steps":["trace[334343034] 'process raft request' (duration: 162.593929ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:00.832631Z","caller":"traceutil/trace.go:171","msg":"trace[765116225] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"201.573712ms","start":"2026-04-28T03:19:00.631035Z","end":"2026-04-28T03:19:00.832609Z","steps":["trace[765116225] 'process raft request' (duration: 201.42608ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:00.83298Z","caller":"traceutil/trace.go:171","msg":"trace[639429864] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"201.645393ms","start":"2026-04-28T03:19:00.631275Z","end":"2026-04-28T03:19:00.832921Z","steps":["trace[639429864] 'process raft request' (duration: 201.287428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:00.833033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.416248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:19:00.833128Z","caller":"traceutil/trace.go:171","msg":"trace[216287823] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1146; }","duration":"196.57111ms","start":"2026-04-28T03:19:00.636537Z","end":"2026-04-28T03:19:00.833108Z","steps":["trace[216287823] 'agreement among raft nodes before linearized reading' (duration: 196.392337ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:00.832827Z","caller":"traceutil/trace.go:171","msg":"trace[338037662] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1182; }","duration":"196.216765ms","start":"2026-04-28T03:19:00.636555Z","end":"2026-04-28T03:19:00.832772Z","steps":["trace[338037662] 'read index received' (duration: 154.492717ms)","trace[338037662] 'applied index is now lower than readState.Index' (duration: 41.720748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:00.833874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.443347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T03:19:00.833934Z","caller":"traceutil/trace.go:171","msg":"trace[1558285640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1146; }","duration":"186.550048ms","start":"2026-04-28T03:19:00.647369Z","end":"2026-04-28T03:19:00.833919Z","steps":["trace[1558285640] 'agreement among raft nodes before linearized reading' (duration: 186.196251ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:01.652566Z","caller":"traceutil/trace.go:171","msg":"trace[1693887322] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"142.0055ms","start":"2026-04-28T03:19:01.510537Z","end":"2026-04-28T03:19:01.652542Z","steps":["trace[1693887322] 'process raft request' (duration: 141.867308ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:03.784775Z","caller":"traceutil/trace.go:171","msg":"trace[1994583872] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"126.781086ms","start":"2026-04-28T03:19:03.657978Z","end":"2026-04-28T03:19:03.784759Z","steps":["trace[1994583872] 'process raft request' (duration: 126.680444ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:03.784886Z","caller":"traceutil/trace.go:171","msg":"trace[1333547234] linearizableReadLoop","detail":"{readStateIndex:1196; appliedIndex:1196; }","duration":"110.09079ms","start":"2026-04-28T03:19:03.6747Z","end":"2026-04-28T03:19:03.784791Z","steps":["trace[1333547234] 'read index received' (duration: 110.0725ms)","trace[1333547234] 'applied index is now lower than readState.Index' (duration: 15.59µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:03.784994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.237863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:19:03.785114Z","caller":"traceutil/trace.go:171","msg":"trace[1590584111] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1156; }","duration":"110.430016ms","start":"2026-04-28T03:19:03.674665Z","end":"2026-04-28T03:19:03.785095Z","steps":["trace[1590584111] 'agreement among raft nodes before linearized reading' (duration: 110.211362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:22.106442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.159755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050256"} {"level":"info","ts":"2026-04-28T03:19:22.106529Z","caller":"traceutil/trace.go:171","msg":"trace[408159249] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1216; }","duration":"109.259206ms","start":"2026-04-28T03:19:21.997251Z","end":"2026-04-28T03:19:22.10651Z","steps":["trace[408159249] 'range keys from in-memory index tree' (duration: 108.214252ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:31.760653Z","caller":"traceutil/trace.go:171","msg":"trace[368763410] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"170.58314ms","start":"2026-04-28T03:19:31.590042Z","end":"2026-04-28T03:19:31.760625Z","steps":["trace[368763410] 'process raft request' (duration: 170.437737ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:33.235621Z","caller":"traceutil/trace.go:171","msg":"trace[62459597] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"110.22282ms","start":"2026-04-28T03:19:33.125369Z","end":"2026-04-28T03:19:33.235592Z","steps":["trace[62459597] 'process raft request' (duration: 110.071238ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:33.347773Z","caller":"traceutil/trace.go:171","msg":"trace[1740447827] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"109.061686ms","start":"2026-04-28T03:19:33.238679Z","end":"2026-04-28T03:19:33.347741Z","steps":["trace[1740447827] 'process raft request' (duration: 81.281405ms)","trace[1740447827] 'compare' (duration: 27.6534ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:19:33.356598Z","caller":"traceutil/trace.go:171","msg":"trace[2094914875] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1299; }","duration":"110.082409ms","start":"2026-04-28T03:19:33.246485Z","end":"2026-04-28T03:19:33.356568Z","steps":["trace[2094914875] 'read index received' (duration: 73.353619ms)","trace[2094914875] 'applied index is now lower than readState.Index' (duration: 36.72772ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:19:33.356634Z","caller":"traceutil/trace.go:171","msg":"trace[314059752] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"115.940078ms","start":"2026-04-28T03:19:33.240662Z","end":"2026-04-28T03:19:33.356602Z","steps":["trace[314059752] 'process raft request' (duration: 115.847366ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:33.356832Z","caller":"traceutil/trace.go:171","msg":"trace[1466746847] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"111.363786ms","start":"2026-04-28T03:19:33.245456Z","end":"2026-04-28T03:19:33.35682Z","steps":["trace[1466746847] 'process raft request' (duration: 111.077463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:33.357067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.558875ms","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-28T03:19:33.357117Z","caller":"traceutil/trace.go:171","msg":"trace[1914851903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"110.653767ms","start":"2026-04-28T03:19:33.246449Z","end":"2026-04-28T03:19:33.357103Z","steps":["trace[1914851903] 'agreement among raft nodes before linearized reading' (duration: 110.479765ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:33.357186Z","caller":"traceutil/trace.go:171","msg":"trace[163430153] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"116.630217ms","start":"2026-04-28T03:19:33.240533Z","end":"2026-04-28T03:19:33.357163Z","steps":["trace[163430153] 'process raft request' (duration: 115.867347ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:40.469851Z","caller":"traceutil/trace.go:171","msg":"trace[1896149317] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"117.318364ms","start":"2026-04-28T03:19:40.352503Z","end":"2026-04-28T03:19:40.469821Z","steps":["trace[1896149317] 'process raft request' (duration: 117.180272ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:41.730493Z","caller":"traceutil/trace.go:171","msg":"trace[512072785] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1358; }","duration":"228.07556ms","start":"2026-04-28T03:19:41.502375Z","end":"2026-04-28T03:19:41.73045Z","steps":["trace[512072785] 'read index received' (duration: 59.613696ms)","trace[512072785] 'applied index is now lower than readState.Index' (duration: 168.460934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:41.730852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.948295ms","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-28T03:19:41.731098Z","caller":"traceutil/trace.go:171","msg":"trace[1953265063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"223.228599ms","start":"2026-04-28T03:19:41.507849Z","end":"2026-04-28T03:19:41.731078Z","steps":["trace[1953265063] 'agreement among raft nodes before linearized reading' (duration: 222.876785ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:41.730826Z","caller":"traceutil/trace.go:171","msg":"trace[339422973] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"243.683391ms","start":"2026-04-28T03:19:41.487097Z","end":"2026-04-28T03:19:41.73078Z","steps":["trace[339422973] 'process raft request' (duration: 189.15164ms)","trace[339422973] 'compare' (duration: 54.098615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:41.730871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.483796ms","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-28T03:19:41.731361Z","caller":"traceutil/trace.go:171","msg":"trace[1958831601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"228.974942ms","start":"2026-04-28T03:19:41.502369Z","end":"2026-04-28T03:19:41.731344Z","steps":["trace[1958831601] 'agreement among raft nodes before linearized reading' (duration: 228.307244ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:49.556602Z","caller":"traceutil/trace.go:171","msg":"trace[1155808278] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"109.394778ms","start":"2026-04-28T03:19:49.447168Z","end":"2026-04-28T03:19:49.556563Z","steps":["trace[1155808278] 'read index received' (duration: 109.150825ms)","trace[1155808278] 'applied index is now lower than readState.Index' (duration: 242.913µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:49.556753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.56837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:19:49.556788Z","caller":"traceutil/trace.go:171","msg":"trace[33877522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"109.700002ms","start":"2026-04-28T03:19:49.447077Z","end":"2026-04-28T03:19:49.556777Z","steps":["trace[33877522] 'agreement among raft nodes before linearized reading' (duration: 109.610921ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:49.556898Z","caller":"traceutil/trace.go:171","msg":"trace[631782699] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"174.485655ms","start":"2026-04-28T03:19:49.38237Z","end":"2026-04-28T03:19:49.556855Z","steps":["trace[631782699] 'process raft request' (duration: 174.028609ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:49.921014Z","caller":"traceutil/trace.go:171","msg":"trace[55073582] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"111.635676ms","start":"2026-04-28T03:19:49.809362Z","end":"2026-04-28T03:19:49.920998Z","steps":["trace[55073582] 'read index received' (duration: 111.435523ms)","trace[55073582] 'applied index is now lower than readState.Index' (duration: 199.503µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:19:49.921284Z","caller":"traceutil/trace.go:171","msg":"trace[63349769] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"155.670161ms","start":"2026-04-28T03:19:49.765416Z","end":"2026-04-28T03:19:49.921086Z","steps":["trace[63349769] 'process raft request' (duration: 155.480858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:49.921413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.03514ms","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-28T03:19:49.922825Z","caller":"traceutil/trace.go:171","msg":"trace[934268914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"113.470538ms","start":"2026-04-28T03:19:49.809334Z","end":"2026-04-28T03:19:49.922805Z","steps":["trace[934268914] 'agreement among raft nodes before linearized reading' (duration: 111.803977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:50.263067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.105133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:19:50.263391Z","caller":"traceutil/trace.go:171","msg":"trace[546840238] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"336.120677ms","start":"2026-04-28T03:19:49.927093Z","end":"2026-04-28T03:19:50.263213Z","steps":["trace[546840238] 'process raft request' (duration: 156.778551ms)","trace[546840238] 'compare' (duration: 178.968561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:50.263544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:49.927073Z","time spent":"336.36719ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:19:50.26362Z","caller":"traceutil/trace.go:171","msg":"trace[595707076] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1394; }","duration":"159.226287ms","start":"2026-04-28T03:19:50.104369Z","end":"2026-04-28T03:19:50.263595Z","steps":["trace[595707076] 'read index received' (duration: 28.537372ms)","trace[595707076] 'applied index is now lower than readState.Index' (duration: 130.687685ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:19:50.263675Z","caller":"traceutil/trace.go:171","msg":"trace[1671358932] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"336.289079ms","start":"2026-04-28T03:19:49.927359Z","end":"2026-04-28T03:19:50.263648Z","steps":["trace[1671358932] 'process raft request' (duration: 335.817302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:50.263793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.407499ms","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-28T03:19:50.263832Z","caller":"traceutil/trace.go:171","msg":"trace[1474348157] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1346; }","duration":"159.485381ms","start":"2026-04-28T03:19:50.104332Z","end":"2026-04-28T03:19:50.263817Z","steps":["trace[1474348157] 'agreement among raft nodes before linearized reading' (duration: 159.38726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:50.263975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:49.927344Z","time spent":"336.462671ms","remote":"127.0.0.1:49278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:20:22.647652Z","caller":"traceutil/trace.go:171","msg":"trace[23355425] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1512; }","duration":"100.141959ms","start":"2026-04-28T03:20:22.547481Z","end":"2026-04-28T03:20:22.647623Z","steps":["trace[23355425] 'read index received' (duration: 75.506506ms)","trace[23355425] 'applied index is now lower than readState.Index' (duration: 24.634343ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:22.647799Z","caller":"traceutil/trace.go:171","msg":"trace[1929521560] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"102.85288ms","start":"2026-04-28T03:20:22.544932Z","end":"2026-04-28T03:20:22.647785Z","steps":["trace[1929521560] 'process raft request' (duration: 78.172656ms)","trace[1929521560] 'compare' (duration: 24.378431ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:22.648339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.820556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-28T03:20:22.648481Z","caller":"traceutil/trace.go:171","msg":"trace[1526770835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:1456; }","duration":"101.024459ms","start":"2026-04-28T03:20:22.547433Z","end":"2026-04-28T03:20:22.648458Z","steps":["trace[1526770835] 'agreement among raft nodes before linearized reading' (duration: 100.789387ms)"],"step_count":1}