{"level":"warn","ts":"2026-05-28T00:45:06.044049Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T00:45:06.044159Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-28T00:45:06.04426Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T00:45:06.044276Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-28T00:45:06.044294Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T00:45:06.044978Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-28T00:45:06.045073Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-28T00:45:06.297026Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"251.683371ms"} {"level":"info","ts":"2026-05-28T00:45:06.450714Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-28T00:45:06.450834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T00:45:06.450862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-28T00:45:06.450881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T00:45:06.451147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-28T00:45:06.451222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-28T00:45:06.453824Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T00:45:06.457619Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T00:45:06.460257Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-28T00:45:06.46335Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T00:45:06.463524Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T00:45:06.463622Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T00:45:06.463641Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T00:45:06.464381Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T00:45:06.464984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-28T00:45:06.465166Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-28T00:45:06.468032Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T00:45:06.468332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T00:45:06.468393Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T00:45:06.468593Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-28T00:45:06.468692Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-28T00:45:06.652213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T00:45:06.652274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T00:45:06.652307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-28T00:45:06.652326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-28T00:45:06.652337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-28T00:45:06.652359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-28T00:45:06.652378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-28T00:45:06.663079Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:45:06.665181Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T00:45:06.665179Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T00:45:06.665241Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T00:45:06.665666Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T00:45:06.665763Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T00:45:06.668592Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:45:06.668768Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:45:06.668844Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T00:45:06.670029Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T00:45:06.670336Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-05-28T00:45:09.158056Z","caller":"traceutil/trace.go:171","msg":"trace[1334853987] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:73; }","duration":"132.31076ms","start":"2026-05-28T00:45:09.025724Z","end":"2026-05-28T00:45:09.158034Z","steps":["trace[1334853987] 'read index received' (duration: 132.168587ms)","trace[1334853987] 'applied index is now lower than readState.Index' (duration: 141.303µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:45:09.158415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.599217ms","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-05-28T00:45:09.158115Z","caller":"traceutil/trace.go:171","msg":"trace[1227247775] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"133.123349ms","start":"2026-05-28T00:45:09.024962Z","end":"2026-05-28T00:45:09.158085Z","steps":["trace[1227247775] 'process raft request' (duration: 132.932335ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:45:09.15871Z","caller":"traceutil/trace.go:171","msg":"trace[1486016827] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"133.014827ms","start":"2026-05-28T00:45:09.025673Z","end":"2026-05-28T00:45:09.158688Z","steps":["trace[1486016827] 'agreement among raft nodes before linearized reading' (duration: 132.561116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:12.175173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960790234797430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T00:45:12.217159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.80086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-28T00:45:12.217201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.29583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:45:12.217267Z","caller":"traceutil/trace.go:171","msg":"trace[1212109808] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:273; }","duration":"542.985904ms","start":"2026-05-28T00:45:11.674264Z","end":"2026-05-28T00:45:12.217249Z","steps":["trace[1212109808] 'range keys from in-memory index tree' (duration: 542.701187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:12.217313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.674248Z","time spent":"543.056166ms","remote":"127.0.0.1:41152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-28T00:45:12.21728Z","caller":"traceutil/trace.go:171","msg":"trace[551384019] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:273; }","duration":"619.416543ms","start":"2026-05-28T00:45:11.597841Z","end":"2026-05-28T00:45:12.217258Z","steps":["trace[551384019] 'range keys from in-memory index tree' (duration: 619.229309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:12.217612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.597822Z","time spent":"619.773132ms","remote":"127.0.0.1:41194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"info","ts":"2026-05-28T00:45:12.217686Z","caller":"traceutil/trace.go:171","msg":"trace[410571025] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"542.893212ms","start":"2026-05-28T00:45:11.674483Z","end":"2026-05-28T00:45:12.217376Z","steps":["trace[410571025] 'process raft request' (duration: 509.464036ms)","trace[410571025] 'compare' (duration: 32.923484ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:45:12.217877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.652071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-05-28T00:45:12.218457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.674474Z","time spent":"543.276601ms","remote":"127.0.0.1:40822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:45:12.218894Z","caller":"traceutil/trace.go:171","msg":"trace[2030520195] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:273; }","duration":"602.902931ms","start":"2026-05-28T00:45:11.615376Z","end":"2026-05-28T00:45:12.218279Z","steps":["trace[2030520195] 'range keys from in-memory index tree' (duration: 601.508727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:12.21912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.615356Z","time spent":"603.651628ms","remote":"127.0.0.1:40856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-28T00:45:12.953218Z","caller":"traceutil/trace.go:171","msg":"trace[1685248459] transaction","detail":"{read_only:false; number_of_response:0; response_revision:274; }","duration":"1.183382849s","start":"2026-05-28T00:45:11.769812Z","end":"2026-05-28T00:45:12.953195Z","steps":["trace[1685248459] 'process raft request' (duration: 1.183044801s)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:45:12.953316Z","caller":"traceutil/trace.go:171","msg":"trace[2059323140] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:278; }","duration":"1.278487562s","start":"2026-05-28T00:45:11.674808Z","end":"2026-05-28T00:45:12.953296Z","steps":["trace[2059323140] 'read index received' (duration: 509.335403ms)","trace[2059323140] 'applied index is now lower than readState.Index' (duration: 769.151209ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:45:12.953358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.769787Z","time spent":"1.183503871s","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:45:12.953417Z","caller":"traceutil/trace.go:171","msg":"trace[2125290174] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"1.162675285s","start":"2026-05-28T00:45:11.790734Z","end":"2026-05-28T00:45:12.953409Z","steps":["trace[2125290174] 'process raft request' (duration: 1.162307366s)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:45:12.953468Z","caller":"traceutil/trace.go:171","msg":"trace[1548620991] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"1.1629002s","start":"2026-05-28T00:45:11.790532Z","end":"2026-05-28T00:45:12.953432Z","steps":["trace[1548620991] 'process raft request' (duration: 1.16247162s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:12.953499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.790725Z","time spent":"1.162717357s","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:45:12.953576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.790526Z","time spent":"1.163000113s","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:45:12.953615Z","caller":"traceutil/trace.go:171","msg":"trace[1875660587] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"1.162656645s","start":"2026-05-28T00:45:11.790919Z","end":"2026-05-28T00:45:12.953576Z","steps":["trace[1875660587] 'process raft request' (duration: 1.162272916s)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:45:12.953623Z","caller":"traceutil/trace.go:171","msg":"trace[421212924] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"1.163082745s","start":"2026-05-28T00:45:11.790485Z","end":"2026-05-28T00:45:12.953568Z","steps":["trace[421212924] 'process raft request' (duration: 1.162496061s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:12.953681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.790912Z","time spent":"1.162736276s","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:45:12.953774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.790478Z","time spent":"1.163228719s","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:45:12.953973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279160508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:45:12.95403Z","caller":"traceutil/trace.go:171","msg":"trace[16859471] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:278; }","duration":"1.27925118s","start":"2026-05-28T00:45:11.674762Z","end":"2026-05-28T00:45:12.954013Z","steps":["trace[16859471] 'agreement among raft nodes before linearized reading' (duration: 1.279091886s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:12.954079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.674746Z","time spent":"1.279317931s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-28T00:45:12.954082Z","caller":"traceutil/trace.go:171","msg":"trace[290116633] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"1.163135266s","start":"2026-05-28T00:45:11.790387Z","end":"2026-05-28T00:45:12.953522Z","steps":["trace[290116633] 'process raft request' (duration: 1.162532362s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:12.954348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:11.790363Z","time spent":"1.163892884s","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:45:13.002396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.478135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:45:13.002435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.44918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-28T00:45:13.002502Z","caller":"traceutil/trace.go:171","msg":"trace[1344523077] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:280; }","duration":"778.551213ms","start":"2026-05-28T00:45:12.223932Z","end":"2026-05-28T00:45:13.002483Z","steps":["trace[1344523077] 'agreement among raft nodes before linearized reading' (duration: 778.396369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:13.002551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:12.223917Z","time spent":"778.618134ms","remote":"127.0.0.1:41152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-28T00:45:13.002627Z","caller":"traceutil/trace.go:171","msg":"trace[575432693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:280; }","duration":"411.600527ms","start":"2026-05-28T00:45:12.590864Z","end":"2026-05-28T00:45:13.002465Z","steps":["trace[575432693] 'agreement among raft nodes before linearized reading' (duration: 411.435763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:13.002686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:12.590856Z","time spent":"411.819562ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T00:45:13.002728Z","caller":"traceutil/trace.go:171","msg":"trace[1904850875] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"779.210138ms","start":"2026-05-28T00:45:12.223465Z","end":"2026-05-28T00:45:13.002675Z","steps":["trace[1904850875] 'process raft request' (duration: 778.173524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:13.003707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:12.223437Z","time spent":"780.199101ms","remote":"127.0.0.1:40822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:45:13.002781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.518834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:45:13.003825Z","caller":"traceutil/trace.go:171","msg":"trace[319200696] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:280; }","duration":"777.576459ms","start":"2026-05-28T00:45:12.22623Z","end":"2026-05-28T00:45:13.003806Z","steps":["trace[319200696] 'agreement among raft nodes before linearized reading' (duration: 776.520544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:13.00388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:12.226219Z","time spent":"777.645611ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"info","ts":"2026-05-28T00:45:13.002911Z","caller":"traceutil/trace.go:171","msg":"trace[287923651] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"779.436194ms","start":"2026-05-28T00:45:12.223466Z","end":"2026-05-28T00:45:13.002902Z","steps":["trace[287923651] 'process raft request' (duration: 778.748737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:13.004015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:12.22345Z","time spent":"780.53207ms","remote":"127.0.0.1:40944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T00:45:13.002411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.236793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T00:45:13.004169Z","caller":"traceutil/trace.go:171","msg":"trace[1254047780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:280; }","duration":"417.051687ms","start":"2026-05-28T00:45:12.587106Z","end":"2026-05-28T00:45:13.004157Z","steps":["trace[1254047780] 'agreement among raft nodes before linearized reading' (duration: 415.208203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:13.004214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:12.587076Z","time spent":"417.123189ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T00:45:14.1497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.985366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-28T00:45:14.149798Z","caller":"traceutil/trace.go:171","msg":"trace[1667363610] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"283.118319ms","start":"2026-05-28T00:45:13.86666Z","end":"2026-05-28T00:45:14.149778Z","steps":["trace[1667363610] 'range keys from in-memory index tree' (duration: 282.819092ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:45:39.134782Z","caller":"traceutil/trace.go:171","msg":"trace[563494342] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"119.948504ms","start":"2026-05-28T00:45:39.014791Z","end":"2026-05-28T00:45:39.13474Z","steps":["trace[563494342] 'read index received' (duration: 119.667947ms)","trace[563494342] 'applied index is now lower than readState.Index' (duration: 278.807µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:45:39.134892Z","caller":"traceutil/trace.go:171","msg":"trace[975235004] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"239.663763ms","start":"2026-05-28T00:45:38.895186Z","end":"2026-05-28T00:45:39.134849Z","steps":["trace[975235004] 'process raft request' (duration: 239.318495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:39.134977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.143039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:45:39.135025Z","caller":"traceutil/trace.go:171","msg":"trace[927102438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"120.249961ms","start":"2026-05-28T00:45:39.014761Z","end":"2026-05-28T00:45:39.135011Z","steps":["trace[927102438] 'agreement among raft nodes before linearized reading' (duration: 120.134208ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:45:44.117732Z","caller":"traceutil/trace.go:171","msg":"trace[2918642] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"103.434119ms","start":"2026-05-28T00:45:44.014267Z","end":"2026-05-28T00:45:44.117701Z","steps":["trace[2918642] 'read index received' (duration: 103.161642ms)","trace[2918642] 'applied index is now lower than readState.Index' (duration: 271.347µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:45:44.117902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.596432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:45:44.117873Z","caller":"traceutil/trace.go:171","msg":"trace[493445505] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"307.968553ms","start":"2026-05-28T00:45:43.809853Z","end":"2026-05-28T00:45:44.117821Z","steps":["trace[493445505] 'process raft request' (duration: 307.663625ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:45:44.117947Z","caller":"traceutil/trace.go:171","msg":"trace[1785959754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"103.701395ms","start":"2026-05-28T00:45:44.01423Z","end":"2026-05-28T00:45:44.117931Z","steps":["trace[1785959754] 'agreement among raft nodes before linearized reading' (duration: 103.596623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:44.118315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:43.809819Z","time spent":"308.161237ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:45:49.555001Z","caller":"traceutil/trace.go:171","msg":"trace[294898449] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"103.605014ms","start":"2026-05-28T00:45:49.451378Z","end":"2026-05-28T00:45:49.554983Z","steps":["trace[294898449] 'read index received' (duration: 103.41851ms)","trace[294898449] 'applied index is now lower than readState.Index' (duration: 185.704µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:45:49.555117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.722977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T00:45:49.555159Z","caller":"traceutil/trace.go:171","msg":"trace[847703149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"103.778488ms","start":"2026-05-28T00:45:49.451373Z","end":"2026-05-28T00:45:49.555152Z","steps":["trace[847703149] 'agreement among raft nodes before linearized reading' (duration: 103.702576ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:45:49.555283Z","caller":"traceutil/trace.go:171","msg":"trace[1179855024] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"115.789815ms","start":"2026-05-28T00:45:49.439471Z","end":"2026-05-28T00:45:49.55526Z","steps":["trace[1179855024] 'process raft request' (duration: 115.338954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:45:49.850361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.890589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:45:49.850651Z","caller":"traceutil/trace.go:171","msg":"trace[875384093] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"323.214349ms","start":"2026-05-28T00:45:49.527389Z","end":"2026-05-28T00:45:49.850604Z","steps":["trace[875384093] 'process raft request' (duration: 141.701224ms)","trace[875384093] 'compare' (duration: 180.4923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:45:49.850794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:45:49.527364Z","time spent":"323.376612ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:45:49.851049Z","caller":"traceutil/trace.go:171","msg":"trace[398632496] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"292.915354ms","start":"2026-05-28T00:45:49.558098Z","end":"2026-05-28T00:45:49.851013Z","steps":["trace[398632496] 'process raft request' (duration: 292.595397ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:45:54.61421Z","caller":"traceutil/trace.go:171","msg":"trace[717309489] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"111.487042ms","start":"2026-05-28T00:45:54.502707Z","end":"2026-05-28T00:45:54.614194Z","steps":["trace[717309489] 'process raft request' (duration: 111.39639ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:46:02.796137Z","caller":"traceutil/trace.go:171","msg":"trace[1591684124] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"119.577355ms","start":"2026-05-28T00:46:02.67653Z","end":"2026-05-28T00:46:02.796107Z","steps":["trace[1591684124] 'read index received' (duration: 119.345629ms)","trace[1591684124] 'applied index is now lower than readState.Index' (duration: 230.656µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:46:02.796215Z","caller":"traceutil/trace.go:171","msg":"trace[1916640855] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"155.055493ms","start":"2026-05-28T00:46:02.64112Z","end":"2026-05-28T00:46:02.796176Z","steps":["trace[1916640855] 'process raft request' (duration: 154.832597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:02.796461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.901433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T00:46:02.796564Z","caller":"traceutil/trace.go:171","msg":"trace[108034945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"120.055276ms","start":"2026-05-28T00:46:02.676487Z","end":"2026-05-28T00:46:02.796542Z","steps":["trace[108034945] 'agreement among raft nodes before linearized reading' (duration: 119.721438ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:46:12.121027Z","caller":"traceutil/trace.go:171","msg":"trace[1871267589] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"105.306344ms","start":"2026-05-28T00:46:12.015696Z","end":"2026-05-28T00:46:12.121002Z","steps":["trace[1871267589] 'read index received' (duration: 105.095889ms)","trace[1871267589] 'applied index is now lower than readState.Index' (duration: 175.225µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:46:12.121156Z","caller":"traceutil/trace.go:171","msg":"trace[331484948] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"136.719105ms","start":"2026-05-28T00:46:11.984373Z","end":"2026-05-28T00:46:12.121092Z","steps":["trace[331484948] 'process raft request' (duration: 136.412047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:12.121194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.484089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:46:12.121418Z","caller":"traceutil/trace.go:171","msg":"trace[1493506034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"105.737514ms","start":"2026-05-28T00:46:12.015638Z","end":"2026-05-28T00:46:12.121376Z","steps":["trace[1493506034] 'agreement among raft nodes before linearized reading' (duration: 105.466418ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:46:13.240986Z","caller":"traceutil/trace.go:171","msg":"trace[1805508845] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"346.656387ms","start":"2026-05-28T00:46:12.894305Z","end":"2026-05-28T00:46:13.240961Z","steps":["trace[1805508845] 'read index received' (duration: 346.385581ms)","trace[1805508845] 'applied index is now lower than readState.Index' (duration: 269.606µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:46:13.241079Z","caller":"traceutil/trace.go:171","msg":"trace[942837674] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"396.501128ms","start":"2026-05-28T00:46:12.844548Z","end":"2026-05-28T00:46:13.241049Z","steps":["trace[942837674] 'process raft request' (duration: 396.251403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:13.241249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:12.844524Z","time spent":"396.620931ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:46:13.241267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.235416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:46:13.241289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.962505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T00:46:13.241343Z","caller":"traceutil/trace.go:171","msg":"trace[994286661] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:690; }","duration":"281.367909ms","start":"2026-05-28T00:46:12.959955Z","end":"2026-05-28T00:46:13.241323Z","steps":["trace[994286661] 'agreement among raft nodes before linearized reading' (duration: 281.220266ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:46:13.241347Z","caller":"traceutil/trace.go:171","msg":"trace[220601317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"347.062797ms","start":"2026-05-28T00:46:12.894267Z","end":"2026-05-28T00:46:13.241329Z","steps":["trace[220601317] 'agreement among raft nodes before linearized reading' (duration: 346.837672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:13.241509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:12.894248Z","time spent":"347.245832ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T00:46:16.33038Z","caller":"traceutil/trace.go:171","msg":"trace[325718303] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"192.721341ms","start":"2026-05-28T00:46:16.137635Z","end":"2026-05-28T00:46:16.330357Z","steps":["trace[325718303] 'process raft request' (duration: 192.522696ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:46:19.779317Z","caller":"traceutil/trace.go:171","msg":"trace[1209044120] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:729; }","duration":"433.772667ms","start":"2026-05-28T00:46:19.345519Z","end":"2026-05-28T00:46:19.779292Z","steps":["trace[1209044120] 'read index received' (duration: 8.266107ms)","trace[1209044120] 'applied index is now lower than readState.Index' (duration: 425.50538ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:46:19.77948Z","caller":"traceutil/trace.go:171","msg":"trace[81525960] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"508.608904ms","start":"2026-05-28T00:46:19.27085Z","end":"2026-05-28T00:46:19.779459Z","steps":["trace[81525960] 'process raft request' (duration: 440.523538ms)","trace[81525960] 'compare' (duration: 67.747168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:46:19.779615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:19.270837Z","time spent":"508.700717ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:46:19.779803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.29072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:46:19.779847Z","caller":"traceutil/trace.go:171","msg":"trace[110541508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"434.367421ms","start":"2026-05-28T00:46:19.345468Z","end":"2026-05-28T00:46:19.779836Z","steps":["trace[110541508] 'agreement among raft nodes before linearized reading' (duration: 434.30192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:19.779879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:19.345441Z","time spent":"434.431283ms","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T00:46:20.391416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.09338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:46:20.391624Z","caller":"traceutil/trace.go:171","msg":"trace[1235520559] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"504.639393ms","start":"2026-05-28T00:46:19.886941Z","end":"2026-05-28T00:46:20.39158Z","steps":["trace[1235520559] 'process raft request' (duration: 278.01672ms)","trace[1235520559] 'compare' (duration: 225.913756ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:46:20.391671Z","caller":"traceutil/trace.go:171","msg":"trace[798433179] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:733; }","duration":"376.363029ms","start":"2026-05-28T00:46:20.015295Z","end":"2026-05-28T00:46:20.391658Z","steps":["trace[798433179] 'read index received' (duration: 149.694305ms)","trace[798433179] 'applied index is now lower than readState.Index' (duration: 226.667964ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:46:20.391737Z","caller":"traceutil/trace.go:171","msg":"trace[811972915] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"454.389622ms","start":"2026-05-28T00:46:19.937342Z","end":"2026-05-28T00:46:20.391731Z","steps":["trace[811972915] 'process raft request' (duration: 454.153887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:20.391761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:19.886921Z","time spent":"504.765535ms","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:46:20.391859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.566783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:46:20.391879Z","caller":"traceutil/trace.go:171","msg":"trace[597337917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"376.611825ms","start":"2026-05-28T00:46:20.015262Z","end":"2026-05-28T00:46:20.391874Z","steps":["trace[597337917] 'agreement among raft nodes before linearized reading' (duration: 376.534453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:20.391896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:20.015247Z","time spent":"376.646085ms","remote":"127.0.0.1:40768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T00:46:20.391779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:19.937325Z","time spent":"454.428214ms","remote":"127.0.0.1:40784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:46:20.892131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960790234798382,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T00:46:21.650151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.577369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:46:21.650274Z","caller":"traceutil/trace.go:171","msg":"trace[1428644168] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"1.258553817s","start":"2026-05-28T00:46:20.391705Z","end":"2026-05-28T00:46:21.650259Z","steps":["trace[1428644168] 'read index received' (duration: 838.771463ms)","trace[1428644168] 'applied index is now lower than readState.Index' (duration: 419.780984ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:46:21.650511Z","caller":"traceutil/trace.go:171","msg":"trace[1075671447] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"1.353908206s","start":"2026-05-28T00:46:20.296593Z","end":"2026-05-28T00:46:21.650501Z","steps":["trace[1075671447] 'process raft request' (duration: 933.902067ms)","trace[1075671447] 'compare' (duration: 419.299642ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:46:21.650565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:20.296577Z","time spent":"1.353963866s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:46:21.650816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.331784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T00:46:21.650837Z","caller":"traceutil/trace.go:171","msg":"trace[2047092593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"293.391205ms","start":"2026-05-28T00:46:21.35744Z","end":"2026-05-28T00:46:21.650832Z","steps":["trace[2047092593] 'agreement among raft nodes before linearized reading' (duration: 293.305013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:21.650836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.52353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:46:21.650933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307054476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T00:46:21.650947Z","caller":"traceutil/trace.go:171","msg":"trace[1116400266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"1.307098617s","start":"2026-05-28T00:46:20.343844Z","end":"2026-05-28T00:46:21.650943Z","steps":["trace[1116400266] 'agreement among raft nodes before linearized reading' (duration: 1.307059816s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:21.650959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:20.343826Z","time spent":"1.307130517s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T00:46:21.65094Z","caller":"traceutil/trace.go:171","msg":"trace[582174240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"636.653153ms","start":"2026-05-28T00:46:21.014247Z","end":"2026-05-28T00:46:21.6509Z","steps":["trace[582174240] 'agreement among raft nodes before linearized reading' (duration: 636.50918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:21.651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:21.014224Z","time spent":"636.758555ms","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T00:46:21.651113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058480068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:46:21.651128Z","caller":"traceutil/trace.go:171","msg":"trace[1116348704] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:711; }","duration":"1.058531819s","start":"2026-05-28T00:46:20.592592Z","end":"2026-05-28T00:46:21.651124Z","steps":["trace[1116348704] 'agreement among raft nodes before linearized reading' (duration: 1.058499209s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:21.651142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:20.592571Z","time spent":"1.058566901s","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-28T00:46:21.654869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260147555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-28T00:46:21.654929Z","caller":"traceutil/trace.go:171","msg":"trace[1178686614] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:711; }","duration":"1.260244098s","start":"2026-05-28T00:46:20.394669Z","end":"2026-05-28T00:46:21.654913Z","steps":["trace[1178686614] 'agreement among raft nodes before linearized reading' (duration: 1.256015417s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:21.654969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:20.394649Z","time spent":"1.260313569s","remote":"127.0.0.1:40922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-28T00:46:23.404146Z","caller":"traceutil/trace.go:171","msg":"trace[431009291] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"338.740669ms","start":"2026-05-28T00:46:23.065373Z","end":"2026-05-28T00:46:23.404114Z","steps":["trace[431009291] 'process raft request' (duration: 338.587785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:23.404317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:23.065341Z","time spent":"338.887222ms","remote":"127.0.0.1:40796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T00:46:23.408347Z","caller":"traceutil/trace.go:171","msg":"trace[173078677] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"140.761117ms","start":"2026-05-28T00:46:23.267567Z","end":"2026-05-28T00:46:23.408328Z","steps":["trace[173078677] 'process raft request' (duration: 140.636864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:36.449122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.313324ms","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-05-28T00:46:36.449379Z","caller":"traceutil/trace.go:171","msg":"trace[1727758337] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:865; }","duration":"490.608571ms","start":"2026-05-28T00:46:35.958746Z","end":"2026-05-28T00:46:36.449355Z","steps":["trace[1727758337] 'range keys from in-memory index tree' (duration: 490.284213ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:46:36.449592Z","caller":"traceutil/trace.go:171","msg":"trace[976676658] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"510.986088ms","start":"2026-05-28T00:46:35.938571Z","end":"2026-05-28T00:46:36.449557Z","steps":["trace[976676658] 'read index received' (duration: 413.499872ms)","trace[976676658] 'applied index is now lower than readState.Index' (duration: 97.484865ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:46:36.449751Z","caller":"traceutil/trace.go:171","msg":"trace[923571654] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"598.766381ms","start":"2026-05-28T00:46:35.850971Z","end":"2026-05-28T00:46:36.449738Z","steps":["trace[923571654] 'process raft request' (duration: 501.151732ms)","trace[923571654] 'compare' (duration: 97.299951ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:46:36.449872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:35.850952Z","time spent":"598.834342ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:46:36.449956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.942116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rvlz6\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-28T00:46:36.450067Z","caller":"traceutil/trace.go:171","msg":"trace[1813186638] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rvlz6; range_end:; response_count:1; response_revision:866; }","duration":"493.09511ms","start":"2026-05-28T00:46:35.956953Z","end":"2026-05-28T00:46:36.450048Z","steps":["trace[1813186638] 'agreement among raft nodes before linearized reading' (duration: 492.854294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:36.450129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:35.95693Z","time spent":"493.189393ms","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rvlz6\" "} {"level":"warn","ts":"2026-05-28T00:46:36.450125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.291066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:46:36.450195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.621942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T00:46:36.450261Z","caller":"traceutil/trace.go:171","msg":"trace[695108935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:866; }","duration":"511.726385ms","start":"2026-05-28T00:46:35.938524Z","end":"2026-05-28T00:46:36.45025Z","steps":["trace[695108935] 'agreement among raft nodes before linearized reading' (duration: 511.409157ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:46:36.450263Z","caller":"traceutil/trace.go:171","msg":"trace[490384812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"436.49175ms","start":"2026-05-28T00:46:36.013729Z","end":"2026-05-28T00:46:36.450221Z","steps":["trace[490384812] 'agreement among raft nodes before linearized reading' (duration: 436.220754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:36.450356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:35.938506Z","time spent":"511.840367ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T00:46:36.450373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:36.013707Z","time spent":"436.643794ms","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T00:46:37.064986Z","caller":"traceutil/trace.go:171","msg":"trace[86027181] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"609.692571ms","start":"2026-05-28T00:46:36.455262Z","end":"2026-05-28T00:46:37.064954Z","steps":["trace[86027181] 'process raft request' (duration: 609.498466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:37.065303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:36.45524Z","time spent":"609.942977ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:46:37.071Z","caller":"traceutil/trace.go:171","msg":"trace[87493276] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:895; }","duration":"455.574815ms","start":"2026-05-28T00:46:36.615405Z","end":"2026-05-28T00:46:37.07098Z","steps":["trace[87493276] 'read index received' (duration: 449.402588ms)","trace[87493276] 'applied index is now lower than readState.Index' (duration: 6.170537ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:46:37.071537Z","caller":"traceutil/trace.go:171","msg":"trace[2003855016] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"612.553089ms","start":"2026-05-28T00:46:36.458971Z","end":"2026-05-28T00:46:37.071524Z","steps":["trace[2003855016] 'process raft request' (duration: 611.913854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:37.071771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.204157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T00:46:37.07193Z","caller":"traceutil/trace.go:171","msg":"trace[177441798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"146.382671ms","start":"2026-05-28T00:46:36.925523Z","end":"2026-05-28T00:46:37.071906Z","steps":["trace[177441798] 'agreement among raft nodes before linearized reading' (duration: 146.048773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:37.071942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.522968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:46:37.071862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:36.458956Z","time spent":"612.847396ms","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:46:37.072016Z","caller":"traceutil/trace.go:171","msg":"trace[681388917] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:868; }","duration":"456.670461ms","start":"2026-05-28T00:46:36.615327Z","end":"2026-05-28T00:46:37.071998Z","steps":["trace[681388917] 'agreement among raft nodes before linearized reading' (duration: 456.555969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:46:37.072064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:46:36.615298Z","time spent":"456.756084ms","remote":"127.0.0.1:41062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-05-28T00:47:00.721399Z","caller":"traceutil/trace.go:171","msg":"trace[2092086821] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"120.895063ms","start":"2026-05-28T00:47:00.600477Z","end":"2026-05-28T00:47:00.721372Z","steps":["trace[2092086821] 'process raft request' (duration: 120.709889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:08.176835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.230469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:47:08.17694Z","caller":"traceutil/trace.go:171","msg":"trace[173894933] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"148.914851ms","start":"2026-05-28T00:47:08.028011Z","end":"2026-05-28T00:47:08.176926Z","steps":["trace[173894933] 'process raft request' (duration: 43.479547ms)","trace[173894933] 'compare' (duration: 105.120377ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:47:11.510734Z","caller":"traceutil/trace.go:171","msg":"trace[1681886190] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"102.850593ms","start":"2026-05-28T00:47:11.407857Z","end":"2026-05-28T00:47:11.510707Z","steps":["trace[1681886190] 'process raft request' (duration: 102.69987ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:12.771657Z","caller":"traceutil/trace.go:171","msg":"trace[60190523] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"295.342563ms","start":"2026-05-28T00:47:12.476291Z","end":"2026-05-28T00:47:12.771633Z","steps":["trace[60190523] 'process raft request' (duration: 295.048136ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:14.056614Z","caller":"traceutil/trace.go:171","msg":"trace[2145655170] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"535.650004ms","start":"2026-05-28T00:47:13.520944Z","end":"2026-05-28T00:47:14.056594Z","steps":["trace[2145655170] 'process raft request' (duration: 535.480799ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:14.05659Z","caller":"traceutil/trace.go:171","msg":"trace[524967905] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"480.541801ms","start":"2026-05-28T00:47:13.576021Z","end":"2026-05-28T00:47:14.056563Z","steps":["trace[524967905] 'read index received' (duration: 480.324986ms)","trace[524967905] 'applied index is now lower than readState.Index' (duration: 215.645µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:47:14.056769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:13.520921Z","time spent":"535.746066ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:47:14.056769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.733985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T00:47:14.056821Z","caller":"traceutil/trace.go:171","msg":"trace[2068001526] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"480.824058ms","start":"2026-05-28T00:47:13.575984Z","end":"2026-05-28T00:47:14.056808Z","steps":["trace[2068001526] 'agreement among raft nodes before linearized reading' (duration: 480.662404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:14.056876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:13.575965Z","time spent":"480.898029ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T00:47:14.378358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.803974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:47:14.378485Z","caller":"traceutil/trace.go:171","msg":"trace[1348321753] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"300.944177ms","start":"2026-05-28T00:47:14.077524Z","end":"2026-05-28T00:47:14.378468Z","steps":["trace[1348321753] 'read index received' (duration: 186.932168ms)","trace[1348321753] 'applied index is now lower than readState.Index' (duration: 114.010899ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:47:14.378536Z","caller":"traceutil/trace.go:171","msg":"trace[2111171760] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"317.866331ms","start":"2026-05-28T00:47:14.060635Z","end":"2026-05-28T00:47:14.378501Z","steps":["trace[2111171760] 'process raft request' (duration: 203.81047ms)","trace[2111171760] 'compare' (duration: 113.648471ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:47:14.378672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.150882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-28T00:47:14.378678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:14.060618Z","time spent":"317.978694ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:47:14.378701Z","caller":"traceutil/trace.go:171","msg":"trace[2012254337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"301.202553ms","start":"2026-05-28T00:47:14.077487Z","end":"2026-05-28T00:47:14.37869Z","steps":["trace[2012254337] 'agreement among raft nodes before linearized reading' (duration: 301.06961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:14.378733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:14.077471Z","time spent":"301.255695ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T00:47:14.378832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.994958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:47:14.378918Z","caller":"traceutil/trace.go:171","msg":"trace[529798066] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1091; }","duration":"254.130981ms","start":"2026-05-28T00:47:14.124762Z","end":"2026-05-28T00:47:14.378893Z","steps":["trace[529798066] 'agreement among raft nodes before linearized reading' (duration: 253.990118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:14.378929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.800909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T00:47:14.378979Z","caller":"traceutil/trace.go:171","msg":"trace[244944858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"161.882981ms","start":"2026-05-28T00:47:14.217082Z","end":"2026-05-28T00:47:14.378965Z","steps":["trace[244944858] 'agreement among raft nodes before linearized reading' (duration: 161.684626ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:14.595501Z","caller":"traceutil/trace.go:171","msg":"trace[1918126106] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"212.222651ms","start":"2026-05-28T00:47:14.383254Z","end":"2026-05-28T00:47:14.595476Z","steps":["trace[1918126106] 'process raft request' (duration: 212.18247ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:14.595854Z","caller":"traceutil/trace.go:171","msg":"trace[902976967] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"213.194315ms","start":"2026-05-28T00:47:14.382646Z","end":"2026-05-28T00:47:14.59584Z","steps":["trace[902976967] 'process raft request' (duration: 193.765901ms)","trace[902976967] 'compare' (duration: 18.87587ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:47:22.92401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.967865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:47:22.924322Z","caller":"traceutil/trace.go:171","msg":"trace[1576024468] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"107.482543ms","start":"2026-05-28T00:47:22.816812Z","end":"2026-05-28T00:47:22.924295Z","steps":["trace[1576024468] 'process raft request' (duration: 107.375141ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:22.924323Z","caller":"traceutil/trace.go:171","msg":"trace[1445346412] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"194.794736ms","start":"2026-05-28T00:47:22.729482Z","end":"2026-05-28T00:47:22.924277Z","steps":["trace[1445346412] 'process raft request' (duration: 73.426741ms)","trace[1445346412] 'compare' (duration: 120.77401ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:47:25.099588Z","caller":"traceutil/trace.go:171","msg":"trace[1251917937] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"133.285799ms","start":"2026-05-28T00:47:24.96628Z","end":"2026-05-28T00:47:25.099565Z","steps":["trace[1251917937] 'process raft request' (duration: 120.515925ms)","trace[1251917937] 'compare' (duration: 12.658321ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:47:25.263866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.31567ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-28T00:47:31.096536Z","caller":"traceutil/trace.go:171","msg":"trace[2044262362] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"107.974325ms","start":"2026-05-28T00:47:30.988523Z","end":"2026-05-28T00:47:31.096497Z","steps":["trace[2044262362] 'process raft request' (duration: 91.922295ms)","trace[2044262362] 'compare' (duration: 15.732832ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:47:31.096984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.57159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T00:47:31.097048Z","caller":"traceutil/trace.go:171","msg":"trace[1047146528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1146; }","duration":"100.677532ms","start":"2026-05-28T00:47:30.996354Z","end":"2026-05-28T00:47:31.097032Z","steps":["trace[1047146528] 'agreement among raft nodes before linearized reading' (duration: 100.028867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:36.690328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960790234799354,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T00:47:36.756232Z","caller":"traceutil/trace.go:171","msg":"trace[2060727459] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"566.939706ms","start":"2026-05-28T00:47:36.189268Z","end":"2026-05-28T00:47:36.756208Z","steps":["trace[2060727459] 'read index received' (duration: 566.916165ms)","trace[2060727459] 'applied index is now lower than readState.Index' (duration: 22.491µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:47:36.756284Z","caller":"traceutil/trace.go:171","msg":"trace[1674191529] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"659.942337ms","start":"2026-05-28T00:47:36.096309Z","end":"2026-05-28T00:47:36.756251Z","steps":["trace[1674191529] 'process raft request' (duration: 659.727292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:36.757136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:36.096286Z","time spent":"660.753936ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:47:36.756594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.307474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T00:47:36.757549Z","caller":"traceutil/trace.go:171","msg":"trace[1753722189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1162; }","duration":"568.298108ms","start":"2026-05-28T00:47:36.189222Z","end":"2026-05-28T00:47:36.75752Z","steps":["trace[1753722189] 'agreement among raft nodes before linearized reading' (duration: 567.10997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:36.757807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:36.1892Z","time spent":"568.534784ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T00:47:37.255266Z","caller":"traceutil/trace.go:171","msg":"trace[2095558732] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"494.581154ms","start":"2026-05-28T00:47:36.760661Z","end":"2026-05-28T00:47:37.255242Z","steps":["trace[2095558732] 'process raft request' (duration: 494.42595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:37.255428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:36.760641Z","time spent":"494.710986ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:47:37.317524Z","caller":"traceutil/trace.go:171","msg":"trace[1846084273] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"348.978079ms","start":"2026-05-28T00:47:36.968529Z","end":"2026-05-28T00:47:37.317507Z","steps":["trace[1846084273] 'read index received' (duration: 287.122215ms)","trace[1846084273] 'applied index is now lower than readState.Index' (duration: 61.854994ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:47:37.317629Z","caller":"traceutil/trace.go:171","msg":"trace[714074977] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"556.895338ms","start":"2026-05-28T00:47:36.760692Z","end":"2026-05-28T00:47:37.317587Z","steps":["trace[714074977] 'process raft request' (duration: 556.632452ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:37.317691Z","caller":"traceutil/trace.go:171","msg":"trace[118040294] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"204.342696ms","start":"2026-05-28T00:47:37.113311Z","end":"2026-05-28T00:47:37.317654Z","steps":["trace[118040294] 'process raft request' (duration: 204.157552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:37.317766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.234014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T00:47:37.3178Z","caller":"traceutil/trace.go:171","msg":"trace[1311087758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1165; }","duration":"349.339576ms","start":"2026-05-28T00:47:36.968451Z","end":"2026-05-28T00:47:37.31779Z","steps":["trace[1311087758] 'agreement among raft nodes before linearized reading' (duration: 349.179482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:37.317816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:36.760671Z","time spent":"557.042112ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:47:37.317881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.875962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:47:37.31791Z","caller":"traceutil/trace.go:171","msg":"trace[1927939440] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1165; }","duration":"287.940774ms","start":"2026-05-28T00:47:37.029959Z","end":"2026-05-28T00:47:37.3179Z","steps":["trace[1927939440] 'agreement among raft nodes before linearized reading' (duration: 287.900043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:37.317832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:36.96842Z","time spent":"349.404208ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T00:47:37.318179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.559743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:47:37.318228Z","caller":"traceutil/trace.go:171","msg":"trace[1738970687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1165; }","duration":"281.647255ms","start":"2026-05-28T00:47:37.036568Z","end":"2026-05-28T00:47:37.318215Z","steps":["trace[1738970687] 'agreement among raft nodes before linearized reading' (duration: 281.574964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:37.318235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.305038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T00:47:37.318295Z","caller":"traceutil/trace.go:171","msg":"trace[1098196678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1165; }","duration":"183.362799ms","start":"2026-05-28T00:47:37.134916Z","end":"2026-05-28T00:47:37.318279Z","steps":["trace[1098196678] 'agreement among raft nodes before linearized reading' (duration: 183.182135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:37.318479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.690101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T00:47:37.318537Z","caller":"traceutil/trace.go:171","msg":"trace[2041967317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"154.788393ms","start":"2026-05-28T00:47:37.163736Z","end":"2026-05-28T00:47:37.318524Z","steps":["trace[2041967317] 'agreement among raft nodes before linearized reading' (duration: 154.575588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:37.485729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.76636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:47:37.485954Z","caller":"traceutil/trace.go:171","msg":"trace[2062264432] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"163.446018ms","start":"2026-05-28T00:47:37.322472Z","end":"2026-05-28T00:47:37.485918Z","steps":["trace[2062264432] 'process raft request' (duration: 47.388541ms)","trace[2062264432] 'compare' (duration: 115.621356ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:47:37.486302Z","caller":"traceutil/trace.go:171","msg":"trace[1766599112] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"163.166371ms","start":"2026-05-28T00:47:37.323111Z","end":"2026-05-28T00:47:37.486277Z","steps":["trace[1766599112] 'process raft request' (duration: 162.71741ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:37.486368Z","caller":"traceutil/trace.go:171","msg":"trace[1894013739] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"163.223473ms","start":"2026-05-28T00:47:37.323113Z","end":"2026-05-28T00:47:37.486336Z","steps":["trace[1894013739] 'process raft request' (duration: 162.989677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:39.823238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960790234799370,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T00:47:39.885462Z","caller":"traceutil/trace.go:171","msg":"trace[486652443] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"621.828024ms","start":"2026-05-28T00:47:39.263609Z","end":"2026-05-28T00:47:39.885437Z","steps":["trace[486652443] 'process raft request' (duration: 621.701091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:39.885744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:39.263587Z","time spent":"621.929377ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:47:40.020265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.030549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:47:40.02035Z","caller":"traceutil/trace.go:171","msg":"trace[1424107846] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1210; }","duration":"698.106239ms","start":"2026-05-28T00:47:39.322228Z","end":"2026-05-28T00:47:40.020334Z","steps":["trace[1424107846] 'read index received' (duration: 563.186146ms)","trace[1424107846] 'applied index is now lower than readState.Index' (duration: 134.918813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:47:40.020495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.025266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:47:40.020526Z","caller":"traceutil/trace.go:171","msg":"trace[86640349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"673.085308ms","start":"2026-05-28T00:47:39.347431Z","end":"2026-05-28T00:47:40.020516Z","steps":["trace[86640349] 'agreement among raft nodes before linearized reading' (duration: 673.020686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:40.020552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:39.347413Z","time spent":"673.130559ms","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T00:47:40.020632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.369181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T00:47:40.020707Z","caller":"traceutil/trace.go:171","msg":"trace[703579217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1170; }","duration":"530.474163ms","start":"2026-05-28T00:47:39.490215Z","end":"2026-05-28T00:47:40.020689Z","steps":["trace[703579217] 'agreement among raft nodes before linearized reading' (duration: 530.263098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:40.020766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:39.490209Z","time spent":"530.545564ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T00:47:40.020771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.709159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T00:47:40.020868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.891542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-28T00:47:40.020911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.639065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T00:47:40.020953Z","caller":"traceutil/trace.go:171","msg":"trace[454970668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"531.014255ms","start":"2026-05-28T00:47:39.48992Z","end":"2026-05-28T00:47:40.020935Z","steps":["trace[454970668] 'agreement among raft nodes before linearized reading' (duration: 530.76907ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:40.020969Z","caller":"traceutil/trace.go:171","msg":"trace[464922476] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:1170; }","duration":"188.736156ms","start":"2026-05-28T00:47:39.83222Z","end":"2026-05-28T00:47:40.020957Z","steps":["trace[464922476] 'agreement among raft nodes before linearized reading' (duration: 188.609833ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:40.020911Z","caller":"traceutil/trace.go:171","msg":"trace[712296148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"530.878283ms","start":"2026-05-28T00:47:39.490019Z","end":"2026-05-28T00:47:40.020897Z","steps":["trace[712296148] 'agreement among raft nodes before linearized reading' (duration: 530.490493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:40.020991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:39.4899Z","time spent":"531.082768ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T00:47:40.021017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:39.490014Z","time spent":"530.991785ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T00:47:40.021277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.045042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T00:47:40.021343Z","caller":"traceutil/trace.go:171","msg":"trace[889192553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"699.148655ms","start":"2026-05-28T00:47:39.322183Z","end":"2026-05-28T00:47:40.021332Z","steps":["trace[889192553] 'agreement among raft nodes before linearized reading' (duration: 698.234873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:40.021377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:39.322157Z","time spent":"699.213036ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T00:47:40.021355Z","caller":"traceutil/trace.go:171","msg":"trace[1588034841] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"698.111199ms","start":"2026-05-28T00:47:39.322317Z","end":"2026-05-28T00:47:40.020428Z","steps":["trace[1588034841] 'process raft request' (duration: 591.856415ms)","trace[1588034841] 'compare' (duration: 105.899426ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:47:40.021622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:39.322288Z","time spent":"699.225016ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:47:40.360625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.056421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:47:40.360725Z","caller":"traceutil/trace.go:171","msg":"trace[1671959820] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"319.635504ms","start":"2026-05-28T00:47:40.041068Z","end":"2026-05-28T00:47:40.360704Z","steps":["trace[1671959820] 'process raft request' (duration: 150.384168ms)","trace[1671959820] 'compare' (duration: 168.976759ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:47:40.36078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:40.041045Z","time spent":"319.706696ms","remote":"127.0.0.1:40784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:47:42.214946Z","caller":"traceutil/trace.go:171","msg":"trace[1184721826] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"321.100518ms","start":"2026-05-28T00:47:41.893822Z","end":"2026-05-28T00:47:42.214922Z","steps":["trace[1184721826] 'process raft request' (duration: 320.964335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:42.215084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:41.893797Z","time spent":"321.2109ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:47:42.258635Z","caller":"traceutil/trace.go:171","msg":"trace[416168221] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1218; }","duration":"163.115729ms","start":"2026-05-28T00:47:42.095495Z","end":"2026-05-28T00:47:42.25861Z","steps":["trace[416168221] 'read index received' (duration: 119.314392ms)","trace[416168221] 'applied index is now lower than readState.Index' (duration: 43.800056ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:47:42.258736Z","caller":"traceutil/trace.go:171","msg":"trace[1245409077] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"232.312186ms","start":"2026-05-28T00:47:42.026379Z","end":"2026-05-28T00:47:42.258691Z","steps":["trace[1245409077] 'process raft request' (duration: 232.081871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:42.258943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.422597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T00:47:42.258995Z","caller":"traceutil/trace.go:171","msg":"trace[1560320955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1177; }","duration":"163.524008ms","start":"2026-05-28T00:47:42.095451Z","end":"2026-05-28T00:47:42.258975Z","steps":["trace[1560320955] 'agreement among raft nodes before linearized reading' (duration: 163.299303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:42.259153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.985685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:47:42.259167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.640892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T00:47:42.259284Z","caller":"traceutil/trace.go:171","msg":"trace[1289774105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1177; }","duration":"163.814336ms","start":"2026-05-28T00:47:42.095451Z","end":"2026-05-28T00:47:42.259265Z","steps":["trace[1289774105] 'agreement among raft nodes before linearized reading' (duration: 163.528739ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:42.259226Z","caller":"traceutil/trace.go:171","msg":"trace[1782199057] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1177; }","duration":"141.046997ms","start":"2026-05-28T00:47:42.118132Z","end":"2026-05-28T00:47:42.259179Z","steps":["trace[1782199057] 'agreement among raft nodes before linearized reading' (duration: 140.994926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:42.259374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.337454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T00:47:42.259453Z","caller":"traceutil/trace.go:171","msg":"trace[15248070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1177; }","duration":"163.489198ms","start":"2026-05-28T00:47:42.095944Z","end":"2026-05-28T00:47:42.259433Z","steps":["trace[15248070] 'agreement among raft nodes before linearized reading' (duration: 163.184801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:42.259683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.081612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T00:47:42.259753Z","caller":"traceutil/trace.go:171","msg":"trace[54792105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1177; }","duration":"164.177724ms","start":"2026-05-28T00:47:42.095562Z","end":"2026-05-28T00:47:42.25974Z","steps":["trace[54792105] 'agreement among raft nodes before linearized reading' (duration: 163.425256ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:42.494941Z","caller":"traceutil/trace.go:171","msg":"trace[2118743017] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"232.289026ms","start":"2026-05-28T00:47:42.26262Z","end":"2026-05-28T00:47:42.494909Z","steps":["trace[2118743017] 'process raft request' (duration: 200.7456ms)","trace[2118743017] 'compare' (duration: 31.379262ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:47:42.532106Z","caller":"traceutil/trace.go:171","msg":"trace[1853152680] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"268.410262ms","start":"2026-05-28T00:47:42.263671Z","end":"2026-05-28T00:47:42.532081Z","steps":["trace[1853152680] 'process raft request' (duration: 268.370841ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:42.532146Z","caller":"traceutil/trace.go:171","msg":"trace[1513209117] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"269.16211ms","start":"2026-05-28T00:47:42.26295Z","end":"2026-05-28T00:47:42.532112Z","steps":["trace[1513209117] 'process raft request' (duration: 268.939254ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:47:42.532261Z","caller":"traceutil/trace.go:171","msg":"trace[946787400] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"269.213361ms","start":"2026-05-28T00:47:42.262994Z","end":"2026-05-28T00:47:42.532207Z","steps":["trace[946787400] 'process raft request' (duration: 269.015776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:59.061373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960790234799610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T00:47:59.51678Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.206881709s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T00:47:59.517209Z","caller":"traceutil/trace.go:171","msg":"trace[1768108706] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"1.207377841s","start":"2026-05-28T00:47:58.309806Z","end":"2026-05-28T00:47:59.517184Z","steps":["trace[1768108706] 'process raft request' (duration: 1.207163646s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:59.517355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:58.309786Z","time spent":"1.207492914s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:47:59.861368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.856308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:47:59.861618Z","caller":"traceutil/trace.go:171","msg":"trace[883324329] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1328; }","duration":"1.301157001s","start":"2026-05-28T00:47:58.560447Z","end":"2026-05-28T00:47:59.861604Z","steps":["trace[883324329] 'read index received' (duration: 956.536176ms)","trace[883324329] 'applied index is now lower than readState.Index' (duration: 344.619795ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:47:59.861691Z","caller":"traceutil/trace.go:171","msg":"trace[838412650] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"1.479914411s","start":"2026-05-28T00:47:58.381768Z","end":"2026-05-28T00:47:59.861683Z","steps":["trace[838412650] 'process raft request' (duration: 1.230672333s)","trace[838412650] 'compare' (duration: 248.5057ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:47:59.861758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:58.381747Z","time spent":"1.479962721s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:47:59.861775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.625032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:47:59.861839Z","caller":"traceutil/trace.go:171","msg":"trace[780811516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"848.713344ms","start":"2026-05-28T00:47:59.013108Z","end":"2026-05-28T00:47:59.861821Z","steps":["trace[780811516] 'agreement among raft nodes before linearized reading' (duration: 848.634152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:59.861869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:59.013093Z","time spent":"848.770305ms","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T00:47:59.861898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252586051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T00:47:59.86194Z","caller":"traceutil/trace.go:171","msg":"trace[642962398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"1.252666712s","start":"2026-05-28T00:47:58.60926Z","end":"2026-05-28T00:47:59.861927Z","steps":["trace[642962398] 'agreement among raft nodes before linearized reading' (duration: 1.25255163s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:59.86178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300429983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:47:59.862Z","caller":"traceutil/trace.go:171","msg":"trace[1215622909] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1282; }","duration":"1.30072033s","start":"2026-05-28T00:47:58.561271Z","end":"2026-05-28T00:47:59.861991Z","steps":["trace[1215622909] 'agreement among raft nodes before linearized reading' (duration: 1.300442363s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:59.862027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:58.561213Z","time spent":"1.300806392s","remote":"127.0.0.1:40456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-28T00:47:59.862042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261626845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T00:47:59.862074Z","caller":"traceutil/trace.go:171","msg":"trace[867003906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"1.261673206s","start":"2026-05-28T00:47:58.600391Z","end":"2026-05-28T00:47:59.862065Z","steps":["trace[867003906] 'agreement among raft nodes before linearized reading' (duration: 1.261595614s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:59.862101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:58.600386Z","time spent":"1.261709356s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T00:47:59.861972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:58.609214Z","time spent":"1.252751984s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T00:47:59.862179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301730514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T00:47:59.862212Z","caller":"traceutil/trace.go:171","msg":"trace[2138629085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"1.301783875s","start":"2026-05-28T00:47:58.56042Z","end":"2026-05-28T00:47:59.862204Z","steps":["trace[2138629085] 'agreement among raft nodes before linearized reading' (duration: 1.301715873s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:59.862216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262078795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-28T00:47:59.862235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:58.560406Z","time spent":"1.301824517s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-28T00:47:59.862242Z","caller":"traceutil/trace.go:171","msg":"trace[1629128369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"1.262134767s","start":"2026-05-28T00:47:58.6001Z","end":"2026-05-28T00:47:59.862235Z","steps":["trace[1629128369] 'agreement among raft nodes before linearized reading' (duration: 1.262062765s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:47:59.862267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:47:58.600077Z","time spent":"1.262183877s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T00:48:11.84605Z","caller":"traceutil/trace.go:171","msg":"trace[1303806217] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"250.483477ms","start":"2026-05-28T00:48:11.595544Z","end":"2026-05-28T00:48:11.846027Z","steps":["trace[1303806217] 'process raft request' (duration: 250.338244ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:13.908187Z","caller":"traceutil/trace.go:171","msg":"trace[1076594667] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"258.527028ms","start":"2026-05-28T00:48:13.64962Z","end":"2026-05-28T00:48:13.908147Z","steps":["trace[1076594667] 'process raft request' (duration: 258.277072ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:18.317757Z","caller":"traceutil/trace.go:171","msg":"trace[1496966861] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"384.369374ms","start":"2026-05-28T00:48:17.933372Z","end":"2026-05-28T00:48:18.317741Z","steps":["trace[1496966861] 'process raft request' (duration: 384.275082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:18.317877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:17.933353Z","time spent":"384.468677ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:48:18.946256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.070417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:48:18.946369Z","caller":"traceutil/trace.go:171","msg":"trace[2104707836] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"981.72975ms","start":"2026-05-28T00:48:17.964631Z","end":"2026-05-28T00:48:18.94636Z","steps":["trace[2104707836] 'read index received' (duration: 353.101634ms)","trace[2104707836] 'applied index is now lower than readState.Index' (duration: 628.627556ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:48:18.94643Z","caller":"traceutil/trace.go:171","msg":"trace[392815444] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"968.18946ms","start":"2026-05-28T00:48:17.978236Z","end":"2026-05-28T00:48:18.946425Z","steps":["trace[392815444] 'process raft request' (duration: 968.086547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:18.94647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:17.978219Z","time spent":"968.230252ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:48:18.946613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.988937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T00:48:18.946634Z","caller":"traceutil/trace.go:171","msg":"trace[1544105214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1376; }","duration":"982.029028ms","start":"2026-05-28T00:48:17.964598Z","end":"2026-05-28T00:48:18.946628Z","steps":["trace[1544105214] 'agreement among raft nodes before linearized reading' (duration: 981.943286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:18.946649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:17.964587Z","time spent":"982.059348ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T00:48:18.946715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.328096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T00:48:18.946731Z","caller":"traceutil/trace.go:171","msg":"trace[1934082230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1376; }","duration":"974.375037ms","start":"2026-05-28T00:48:17.972352Z","end":"2026-05-28T00:48:18.946727Z","steps":["trace[1934082230] 'agreement among raft nodes before linearized reading' (duration: 974.336396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:18.946743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:17.972331Z","time spent":"974.409747ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T00:48:18.946766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.217704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-28T00:48:18.946801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.662146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T00:48:18.946813Z","caller":"traceutil/trace.go:171","msg":"trace[1011445208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"969.696506ms","start":"2026-05-28T00:48:17.977112Z","end":"2026-05-28T00:48:18.946809Z","steps":["trace[1011445208] 'agreement among raft nodes before linearized reading' (duration: 969.660335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:18.946824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:17.977101Z","time spent":"969.720046ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T00:48:18.946815Z","caller":"traceutil/trace.go:171","msg":"trace[1473240504] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1376; }","duration":"933.295286ms","start":"2026-05-28T00:48:18.013508Z","end":"2026-05-28T00:48:18.946803Z","steps":["trace[1473240504] 'agreement among raft nodes before linearized reading' (duration: 933.211294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:18.946799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.783364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T00:48:18.946852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:18.013496Z","time spent":"933.345567ms","remote":"127.0.0.1:41156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-28T00:48:18.946883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.913706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T00:48:18.946876Z","caller":"traceutil/trace.go:171","msg":"trace[1739793893] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1376; }","duration":"763.907657ms","start":"2026-05-28T00:48:18.182949Z","end":"2026-05-28T00:48:18.946856Z","steps":["trace[1739793893] 'agreement among raft nodes before linearized reading' (duration: 763.767774ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:18.946898Z","caller":"traceutil/trace.go:171","msg":"trace[1621964718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1376; }","duration":"973.937366ms","start":"2026-05-28T00:48:17.972956Z","end":"2026-05-28T00:48:18.946894Z","steps":["trace[1621964718] 'agreement among raft nodes before linearized reading' (duration: 973.899375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:18.946879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.816693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-28T00:48:18.946911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:17.97295Z","time spent":"973.957997ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T00:48:18.946919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:18.18293Z","time spent":"763.979299ms","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-28T00:48:18.946938Z","caller":"traceutil/trace.go:171","msg":"trace[915723738] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1376; }","duration":"877.916335ms","start":"2026-05-28T00:48:18.069005Z","end":"2026-05-28T00:48:18.946921Z","steps":["trace[915723738] 'agreement among raft nodes before linearized reading' (duration: 877.72823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:18.946984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:18.068962Z","time spent":"878.008107ms","remote":"127.0.0.1:40822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":146,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-28T00:48:18.946405Z","caller":"traceutil/trace.go:171","msg":"trace[1502663356] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"1.011853334s","start":"2026-05-28T00:48:17.934526Z","end":"2026-05-28T00:48:18.946379Z","steps":["trace[1502663356] 'process raft request' (duration: 742.591222ms)","trace[1502663356] 'compare' (duration: 268.785911ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:48:18.947098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:17.934516Z","time spent":"1.012566211s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:48:18.947142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.478966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:48:18.947213Z","caller":"traceutil/trace.go:171","msg":"trace[856521024] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1376; }","duration":"487.559458ms","start":"2026-05-28T00:48:18.459619Z","end":"2026-05-28T00:48:18.947178Z","steps":["trace[856521024] 'agreement among raft nodes before linearized reading' (duration: 487.490756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:18.947245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:18.459599Z","time spent":"487.63746ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-05-28T00:48:19.22201Z","caller":"traceutil/trace.go:171","msg":"trace[1498217591] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"270.736957ms","start":"2026-05-28T00:48:18.951251Z","end":"2026-05-28T00:48:19.221988Z","steps":["trace[1498217591] 'process raft request' (duration: 270.569863ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:19.222304Z","caller":"traceutil/trace.go:171","msg":"trace[720080461] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"270.910641ms","start":"2026-05-28T00:48:18.951372Z","end":"2026-05-28T00:48:19.222283Z","steps":["trace[720080461] 'process raft request' (duration: 270.581383ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:19.222382Z","caller":"traceutil/trace.go:171","msg":"trace[1608903805] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1428; }","duration":"207.90201ms","start":"2026-05-28T00:48:19.014472Z","end":"2026-05-28T00:48:19.222374Z","steps":["trace[1608903805] 'read index received' (duration: 31.338212ms)","trace[1608903805] 'applied index is now lower than readState.Index' (duration: 176.563088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:48:19.222463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.977561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:48:19.222483Z","caller":"traceutil/trace.go:171","msg":"trace[366274696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1380; }","duration":"208.041213ms","start":"2026-05-28T00:48:19.014436Z","end":"2026-05-28T00:48:19.222477Z","steps":["trace[366274696] 'agreement among raft nodes before linearized reading' (duration: 207.971752ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:19.22252Z","caller":"traceutil/trace.go:171","msg":"trace[1798449876] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"271.138016ms","start":"2026-05-28T00:48:18.951373Z","end":"2026-05-28T00:48:19.222511Z","steps":["trace[1798449876] 'process raft request' (duration: 270.811168ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:20.70201Z","caller":"traceutil/trace.go:171","msg":"trace[1760861327] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"162.613628ms","start":"2026-05-28T00:48:20.539373Z","end":"2026-05-28T00:48:20.701987Z","steps":["trace[1760861327] 'read index received' (duration: 162.475415ms)","trace[1760861327] 'applied index is now lower than readState.Index' (duration: 136.973µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:48:20.702513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.11993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-28T00:48:20.702487Z","caller":"traceutil/trace.go:171","msg":"trace[697829023] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"379.940381ms","start":"2026-05-28T00:48:20.322508Z","end":"2026-05-28T00:48:20.702449Z","steps":["trace[697829023] 'process raft request' (duration: 379.357397ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:20.702625Z","caller":"traceutil/trace.go:171","msg":"trace[1509518542] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1383; }","duration":"163.178751ms","start":"2026-05-28T00:48:20.539367Z","end":"2026-05-28T00:48:20.702546Z","steps":["trace[1509518542] 'agreement among raft nodes before linearized reading' (duration: 162.71409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:20.702682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:20.322491Z","time spent":"380.087784ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:48:21.452023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960790234799844,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T00:48:21.952173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960790234799844,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T00:48:22.357249Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.030335111s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T00:48:22.357839Z","caller":"traceutil/trace.go:171","msg":"trace[1981208132] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"1.406080522s","start":"2026-05-28T00:48:20.951734Z","end":"2026-05-28T00:48:22.357815Z","steps":["trace[1981208132] 'read index received' (duration: 1.405723054s)","trace[1981208132] 'applied index is now lower than readState.Index' (duration: 356.288µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:48:22.357893Z","caller":"traceutil/trace.go:171","msg":"trace[1531487969] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"1.651521641s","start":"2026-05-28T00:48:20.70635Z","end":"2026-05-28T00:48:22.357871Z","steps":["trace[1531487969] 'process raft request' (duration: 1.651282965s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:22.35801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:20.706329Z","time spent":"1.651612702s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:48:22.358092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406330128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T00:48:22.358138Z","caller":"traceutil/trace.go:171","msg":"trace[6363201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1384; }","duration":"1.406433991s","start":"2026-05-28T00:48:20.951693Z","end":"2026-05-28T00:48:22.358127Z","steps":["trace[6363201] 'agreement among raft nodes before linearized reading' (duration: 1.406268497s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:22.358144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400079821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T00:48:22.358168Z","caller":"traceutil/trace.go:171","msg":"trace[1754182404] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1384; }","duration":"1.400103271s","start":"2026-05-28T00:48:20.958057Z","end":"2026-05-28T00:48:22.35816Z","steps":["trace[1754182404] 'agreement among raft nodes before linearized reading' (duration: 1.40005309s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:22.358175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:20.951675Z","time spent":"1.406491833s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T00:48:22.35819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:20.958028Z","time spent":"1.400156652s","remote":"127.0.0.1:41184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-28T00:48:22.358351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131559766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-28T00:48:22.358383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308646247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T00:48:22.358385Z","caller":"traceutil/trace.go:171","msg":"trace[524093027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1384; }","duration":"1.131627528s","start":"2026-05-28T00:48:21.226748Z","end":"2026-05-28T00:48:22.358376Z","steps":["trace[524093027] 'agreement among raft nodes before linearized reading' (duration: 1.131544516s)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:22.358412Z","caller":"traceutil/trace.go:171","msg":"trace[105727740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1384; }","duration":"1.308695248s","start":"2026-05-28T00:48:21.049708Z","end":"2026-05-28T00:48:22.358403Z","steps":["trace[105727740] 'agreement among raft nodes before linearized reading' (duration: 1.308596316s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:22.358437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:21.049698Z","time spent":"1.308733189s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T00:48:22.358481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.951238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-28T00:48:22.358481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344544016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:48:22.358512Z","caller":"traceutil/trace.go:171","msg":"trace[1192637774] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1384; }","duration":"469.02217ms","start":"2026-05-28T00:48:21.889481Z","end":"2026-05-28T00:48:22.358503Z","steps":["trace[1192637774] 'agreement among raft nodes before linearized reading' (duration: 468.947188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:22.358412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:21.226728Z","time spent":"1.131678349s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-28T00:48:22.358527Z","caller":"traceutil/trace.go:171","msg":"trace[133136946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1384; }","duration":"1.344622008s","start":"2026-05-28T00:48:21.013889Z","end":"2026-05-28T00:48:22.358511Z","steps":["trace[133136946] 'agreement among raft nodes before linearized reading' (duration: 1.344547266s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:22.358538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:21.889458Z","time spent":"469.07331ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-28T00:48:22.358559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:21.013869Z","time spent":"1.34468342s","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T00:48:22.358659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131845373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-28T00:48:22.358682Z","caller":"traceutil/trace.go:171","msg":"trace[1547327835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1384; }","duration":"1.131908715s","start":"2026-05-28T00:48:21.226766Z","end":"2026-05-28T00:48:22.358675Z","steps":["trace[1547327835] 'agreement among raft nodes before linearized reading' (duration: 1.131838313s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:22.358672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131575577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-28T00:48:22.358707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:21.226744Z","time spent":"1.131956995s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T00:48:22.35874Z","caller":"traceutil/trace.go:171","msg":"trace[1939936887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1384; }","duration":"1.13170082s","start":"2026-05-28T00:48:21.227014Z","end":"2026-05-28T00:48:22.358715Z","steps":["trace[1939936887] 'agreement among raft nodes before linearized reading' (duration: 1.131461184s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:22.358788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:21.226986Z","time spent":"1.131792952s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T00:48:22.469987Z","caller":"traceutil/trace.go:171","msg":"trace[1828691140] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"106.256784ms","start":"2026-05-28T00:48:22.363701Z","end":"2026-05-28T00:48:22.469957Z","steps":["trace[1828691140] 'process raft request' (duration: 77.420632ms)","trace[1828691140] 'compare' (duration: 28.627667ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:48:22.498178Z","caller":"traceutil/trace.go:171","msg":"trace[1220798870] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1437; }","duration":"132.460034ms","start":"2026-05-28T00:48:22.365693Z","end":"2026-05-28T00:48:22.498153Z","steps":["trace[1220798870] 'read index received' (duration: 75.438555ms)","trace[1220798870] 'applied index is now lower than readState.Index' (duration: 57.020589ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:48:22.498307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.602658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:48:22.498338Z","caller":"traceutil/trace.go:171","msg":"trace[1797852595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1390; }","duration":"132.676159ms","start":"2026-05-28T00:48:22.365652Z","end":"2026-05-28T00:48:22.498329Z","steps":["trace[1797852595] 'agreement among raft nodes before linearized reading' (duration: 132.590767ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:22.498551Z","caller":"traceutil/trace.go:171","msg":"trace[628355765] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"134.608295ms","start":"2026-05-28T00:48:22.363931Z","end":"2026-05-28T00:48:22.498539Z","steps":["trace[628355765] 'process raft request' (duration: 134.119494ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:22.498491Z","caller":"traceutil/trace.go:171","msg":"trace[2085334891] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"134.546914ms","start":"2026-05-28T00:48:22.363899Z","end":"2026-05-28T00:48:22.498446Z","steps":["trace[2085334891] 'process raft request' (duration: 134.114794ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:22.498585Z","caller":"traceutil/trace.go:171","msg":"trace[2093481639] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"134.674387ms","start":"2026-05-28T00:48:22.363882Z","end":"2026-05-28T00:48:22.498556Z","steps":["trace[2093481639] 'process raft request' (duration: 134.025352ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:22.498747Z","caller":"traceutil/trace.go:171","msg":"trace[853738966] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"134.740448ms","start":"2026-05-28T00:48:22.36399Z","end":"2026-05-28T00:48:22.49873Z","steps":["trace[853738966] 'process raft request' (duration: 134.130384ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:22.498808Z","caller":"traceutil/trace.go:171","msg":"trace[2131313672] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"134.83358ms","start":"2026-05-28T00:48:22.363957Z","end":"2026-05-28T00:48:22.498791Z","steps":["trace[2131313672] 'process raft request' (duration: 134.123784ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:22.826742Z","caller":"traceutil/trace.go:171","msg":"trace[2019595719] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"130.556029ms","start":"2026-05-28T00:48:22.696154Z","end":"2026-05-28T00:48:22.82671Z","steps":["trace[2019595719] 'process raft request' (duration: 130.497348ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:22.826833Z","caller":"traceutil/trace.go:171","msg":"trace[1541007847] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"135.328772ms","start":"2026-05-28T00:48:22.691468Z","end":"2026-05-28T00:48:22.826797Z","steps":["trace[1541007847] 'process raft request' (duration: 135.027385ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:22.845566Z","caller":"traceutil/trace.go:171","msg":"trace[802617473] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"138.417615ms","start":"2026-05-28T00:48:22.707124Z","end":"2026-05-28T00:48:22.845542Z","steps":["trace[802617473] 'process raft request' (duration: 138.311422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:27.010978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960790234799913,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T00:48:27.212344Z","caller":"traceutil/trace.go:171","msg":"trace[1560053468] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"703.805735ms","start":"2026-05-28T00:48:26.508514Z","end":"2026-05-28T00:48:27.21232Z","steps":["trace[1560053468] 'process raft request' (duration: 703.662042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:27.212485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:26.508495Z","time spent":"703.915217ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:48:27.500041Z","caller":"traceutil/trace.go:171","msg":"trace[250975918] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1477; }","duration":"989.985668ms","start":"2026-05-28T00:48:26.51003Z","end":"2026-05-28T00:48:27.500015Z","steps":["trace[250975918] 'read index received' (duration: 702.147516ms)","trace[250975918] 'applied index is now lower than readState.Index' (duration: 287.836922ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:48:27.500123Z","caller":"traceutil/trace.go:171","msg":"trace[384344603] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"643.317724ms","start":"2026-05-28T00:48:26.856768Z","end":"2026-05-28T00:48:27.500086Z","steps":["trace[384344603] 'process raft request' (duration: 606.130434ms)","trace[384344603] 'compare' (duration: 37.019976ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:48:27.500249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.201813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-28T00:48:27.50029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.44473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-28T00:48:27.500317Z","caller":"traceutil/trace.go:171","msg":"trace[143823282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1425; }","duration":"990.314675ms","start":"2026-05-28T00:48:26.509987Z","end":"2026-05-28T00:48:27.500302Z","steps":["trace[143823282] 'agreement among raft nodes before linearized reading' (duration: 990.119081ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:27.500324Z","caller":"traceutil/trace.go:171","msg":"trace[951498578] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1425; }","duration":"136.524471ms","start":"2026-05-28T00:48:27.363789Z","end":"2026-05-28T00:48:27.500314Z","steps":["trace[951498578] 'agreement among raft nodes before linearized reading' (duration: 136.374307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:27.500332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.009148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T00:48:27.500409Z","caller":"traceutil/trace.go:171","msg":"trace[1920466248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1425; }","duration":"990.11498ms","start":"2026-05-28T00:48:26.510264Z","end":"2026-05-28T00:48:27.500379Z","steps":["trace[1920466248] 'agreement among raft nodes before linearized reading' (duration: 989.896165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:27.500419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.526219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T00:48:27.50044Z","caller":"traceutil/trace.go:171","msg":"trace[1577704009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1425; }","duration":"987.560081ms","start":"2026-05-28T00:48:26.512874Z","end":"2026-05-28T00:48:27.500434Z","steps":["trace[1577704009] 'agreement among raft nodes before linearized reading' (duration: 987.502009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:27.500471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:26.512868Z","time spent":"987.599301ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T00:48:27.500351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:26.856739Z","time spent":"643.455197ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T00:48:27.500542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.435023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-28T00:48:27.50058Z","caller":"traceutil/trace.go:171","msg":"trace[453835973] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1425; }","duration":"129.512605ms","start":"2026-05-28T00:48:27.371058Z","end":"2026-05-28T00:48:27.500571Z","steps":["trace[453835973] 'agreement among raft nodes before linearized reading' (duration: 129.424763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:27.500706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.97092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T00:48:27.500747Z","caller":"traceutil/trace.go:171","msg":"trace[2075823531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1425; }","duration":"988.01298ms","start":"2026-05-28T00:48:26.512709Z","end":"2026-05-28T00:48:27.500722Z","steps":["trace[2075823531] 'agreement among raft nodes before linearized reading' (duration: 987.941499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:27.500355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:26.509966Z","time spent":"990.377256ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T00:48:27.500776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:26.512699Z","time spent":"988.069332ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T00:48:27.500441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:26.510253Z","time spent":"990.181343ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T00:48:28.264293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.538858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T00:48:28.264427Z","caller":"traceutil/trace.go:171","msg":"trace[1204222223] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"758.997461ms","start":"2026-05-28T00:48:27.505409Z","end":"2026-05-28T00:48:28.264406Z","steps":["trace[1204222223] 'process raft request' (duration: 268.252068ms)","trace[1204222223] 'compare' (duration: 490.350154ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:48:28.26454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:27.505389Z","time spent":"759.115513ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:48:28.26693Z","caller":"traceutil/trace.go:171","msg":"trace[44891680] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"760.528998ms","start":"2026-05-28T00:48:27.506382Z","end":"2026-05-28T00:48:28.266911Z","steps":["trace[44891680] 'process raft request' (duration: 760.320153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:28.267672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:27.506369Z","time spent":"761.194943ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:48:28.267952Z","caller":"traceutil/trace.go:171","msg":"trace[489775497] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"761.599662ms","start":"2026-05-28T00:48:27.506325Z","end":"2026-05-28T00:48:28.267924Z","steps":["trace[489775497] 'process raft request' (duration: 760.480996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:28.268111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:27.506316Z","time spent":"761.707225ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:48:28.268265Z","caller":"traceutil/trace.go:171","msg":"trace[1181857614] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"761.644684ms","start":"2026-05-28T00:48:27.506597Z","end":"2026-05-28T00:48:28.268242Z","steps":["trace[1181857614] 'process raft request' (duration: 760.265471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:28.268325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:27.506581Z","time spent":"761.711175ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:48:28.268401Z","caller":"traceutil/trace.go:171","msg":"trace[243250805] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"761.633933ms","start":"2026-05-28T00:48:27.506654Z","end":"2026-05-28T00:48:28.268288Z","steps":["trace[243250805] 'process raft request' (duration: 760.2362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:28.268558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:27.506633Z","time spent":"761.836998ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:48:28.268718Z","caller":"traceutil/trace.go:171","msg":"trace[474258406] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"761.338766ms","start":"2026-05-28T00:48:27.507344Z","end":"2026-05-28T00:48:28.268683Z","steps":["trace[474258406] 'process raft request' (duration: 759.566785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:28.268801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:27.507337Z","time spent":"761.417908ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:48:28.269397Z","caller":"traceutil/trace.go:171","msg":"trace[653655237] linearizableReadLoop","detail":"{readStateIndex:1486; appliedIndex:1479; }","duration":"638.52114ms","start":"2026-05-28T00:48:27.6308Z","end":"2026-05-28T00:48:28.269321Z","steps":["trace[653655237] 'read index received' (duration: 142.884422ms)","trace[653655237] 'applied index is now lower than readState.Index' (duration: 495.634408ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:48:28.270166Z","caller":"traceutil/trace.go:171","msg":"trace[1486357898] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"762.589416ms","start":"2026-05-28T00:48:27.50755Z","end":"2026-05-28T00:48:28.27014Z","steps":["trace[1486357898] 'process raft request' (duration: 760.912636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:28.270696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.889293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-28T00:48:28.270749Z","caller":"traceutil/trace.go:171","msg":"trace[562226423] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1432; }","duration":"568.975425ms","start":"2026-05-28T00:48:27.701752Z","end":"2026-05-28T00:48:28.270728Z","steps":["trace[562226423] 'agreement among raft nodes before linearized reading' (duration: 568.836501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:28.27078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:27.701741Z","time spent":"569.033196ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-28T00:48:28.27024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.447822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:48:28.27083Z","caller":"traceutil/trace.go:171","msg":"trace[481596685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1432; }","duration":"640.080407ms","start":"2026-05-28T00:48:27.630736Z","end":"2026-05-28T00:48:28.270816Z","steps":["trace[481596685] 'agreement among raft nodes before linearized reading' (duration: 639.471682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:28.270869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:27.630712Z","time spent":"640.146019ms","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T00:48:28.270912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.653514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:48:28.270948Z","caller":"traceutil/trace.go:171","msg":"trace[947776517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1432; }","duration":"256.706774ms","start":"2026-05-28T00:48:28.014225Z","end":"2026-05-28T00:48:28.270931Z","steps":["trace[947776517] 'agreement among raft nodes before linearized reading' (duration: 256.667674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:48:28.271386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:48:27.507542Z","time spent":"763.170339ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:48:47.523816Z","caller":"traceutil/trace.go:171","msg":"trace[1436583136] linearizableReadLoop","detail":"{readStateIndex:1575; appliedIndex:1574; }","duration":"108.068838ms","start":"2026-05-28T00:48:47.415724Z","end":"2026-05-28T00:48:47.523793Z","steps":["trace[1436583136] 'read index received' (duration: 107.901284ms)","trace[1436583136] 'applied index is now lower than readState.Index' (duration: 166.244µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:48:47.523959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.220471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T00:48:47.523992Z","caller":"traceutil/trace.go:171","msg":"trace[204598640] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1517; }","duration":"108.300173ms","start":"2026-05-28T00:48:47.415681Z","end":"2026-05-28T00:48:47.523981Z","steps":["trace[204598640] 'agreement among raft nodes before linearized reading' (duration: 108.206871ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:47.524054Z","caller":"traceutil/trace.go:171","msg":"trace[339763924] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"176.89528ms","start":"2026-05-28T00:48:47.347127Z","end":"2026-05-28T00:48:47.524022Z","steps":["trace[339763924] 'process raft request' (duration: 176.522151ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:48:47.698986Z","caller":"traceutil/trace.go:171","msg":"trace[916419151] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"106.637014ms","start":"2026-05-28T00:48:47.592324Z","end":"2026-05-28T00:48:47.698961Z","steps":["trace[916419151] 'process raft request' (duration: 106.339537ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:49:15.716613Z","caller":"traceutil/trace.go:171","msg":"trace[2144634932] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"123.432161ms","start":"2026-05-28T00:49:15.59316Z","end":"2026-05-28T00:49:15.716592Z","steps":["trace[2144634932] 'process raft request' (duration: 123.336699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:49:26.832745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.019221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T00:49:26.832815Z","caller":"traceutil/trace.go:171","msg":"trace[1933370039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1696; }","duration":"140.118854ms","start":"2026-05-28T00:49:26.692681Z","end":"2026-05-28T00:49:26.8328Z","steps":["trace[1933370039] 'range keys from in-memory index tree' (duration: 139.890618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:49:26.832813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.401914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-28T00:49:26.832872Z","caller":"traceutil/trace.go:171","msg":"trace[1770453069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1696; }","duration":"166.528136ms","start":"2026-05-28T00:49:26.666327Z","end":"2026-05-28T00:49:26.832855Z","steps":["trace[1770453069] 'range keys from in-memory index tree' (duration: 166.24859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:49:26.83299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.992049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T00:49:26.833006Z","caller":"traceutil/trace.go:171","msg":"trace[1456124663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1696; }","duration":"131.029829ms","start":"2026-05-28T00:49:26.701971Z","end":"2026-05-28T00:49:26.833001Z","steps":["trace[1456124663] 'range keys from in-memory index tree' (duration: 130.884576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:49:26.833246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.175046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-28T00:49:26.833281Z","caller":"traceutil/trace.go:171","msg":"trace[5936982] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1696; }","duration":"132.237408ms","start":"2026-05-28T00:49:26.701036Z","end":"2026-05-28T00:49:26.833273Z","steps":["trace[5936982] 'range keys from in-memory index tree' (duration: 131.964342ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:49:33.128121Z","caller":"traceutil/trace.go:171","msg":"trace[936031275] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"136.090428ms","start":"2026-05-28T00:49:32.992006Z","end":"2026-05-28T00:49:33.128097Z","steps":["trace[936031275] 'process raft request' (duration: 136.028597ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:49:33.128156Z","caller":"traceutil/trace.go:171","msg":"trace[2084677107] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"136.338534ms","start":"2026-05-28T00:49:32.991787Z","end":"2026-05-28T00:49:33.128126Z","steps":["trace[2084677107] 'process raft request' (duration: 96.236799ms)","trace[2084677107] 'compare' (duration: 39.86369ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:49:34.836459Z","caller":"traceutil/trace.go:171","msg":"trace[1909681872] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"139.891578ms","start":"2026-05-28T00:49:34.696543Z","end":"2026-05-28T00:49:34.836434Z","steps":["trace[1909681872] 'process raft request' (duration: 139.730894ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:49:45.503725Z","caller":"traceutil/trace.go:171","msg":"trace[455907544] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"101.53182ms","start":"2026-05-28T00:49:45.402174Z","end":"2026-05-28T00:49:45.503706Z","steps":["trace[455907544] 'process raft request' (duration: 101.394747ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:49:47.146299Z","caller":"traceutil/trace.go:171","msg":"trace[928001887] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"105.100064ms","start":"2026-05-28T00:49:47.041113Z","end":"2026-05-28T00:49:47.146213Z","steps":["trace[928001887] 'process raft request' (duration: 104.829788ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:51:19.555237Z","caller":"traceutil/trace.go:171","msg":"trace[1845797313] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"138.338731ms","start":"2026-05-28T00:51:19.416828Z","end":"2026-05-28T00:51:19.555167Z","steps":["trace[1845797313] 'process raft request' (duration: 138.056314ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T00:52:13.094094Z","caller":"traceutil/trace.go:171","msg":"trace[1076614744] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"443.364917ms","start":"2026-05-28T00:52:12.650701Z","end":"2026-05-28T00:52:13.094066Z","steps":["trace[1076614744] 'process raft request' (duration: 437.246822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T00:52:13.094262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:52:12.650682Z","time spent":"443.506379ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:52:22.276888Z","caller":"traceutil/trace.go:171","msg":"trace[898689165] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"190.745016ms","start":"2026-05-28T00:52:22.086093Z","end":"2026-05-28T00:52:22.276838Z","steps":["trace[898689165] 'process raft request' (duration: 94.998029ms)","trace[898689165] 'compare' (duration: 95.502911ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T00:52:23.011034Z","caller":"traceutil/trace.go:171","msg":"trace[1612048610] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"406.8272ms","start":"2026-05-28T00:52:22.604175Z","end":"2026-05-28T00:52:23.011002Z","steps":["trace[1612048610] 'process raft request' (duration: 368.156024ms)","trace[1612048610] 'compare' (duration: 38.42923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T00:52:23.011164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T00:52:22.604137Z","time spent":"406.960493ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T00:54:06.196594Z","caller":"traceutil/trace.go:171","msg":"trace[1764604048] transaction","detail":"{read_only:false; response_revision:3010; number_of_response:1; }","duration":"119.614787ms","start":"2026-05-28T00:54:06.07695Z","end":"2026-05-28T00:54:06.196565Z","steps":["trace[1764604048] 'process raft request' (duration: 119.456983ms)"],"step_count":1}