{"level":"warn","ts":"2026-03-31T18:26:20.370533Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T18:26:20.370682Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.124:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.124:2380","--initial-cluster=instance=https://199.19.213.124:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.124:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.124: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-03-31T18:26:20.370768Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T18:26:20.370784Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-03-31T18:26:20.370803Z","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-03-31T18:26:20.371676Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"]} {"level":"info","ts":"2026-03-31T18:26:20.3718Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.124: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-03-31T18:26:20.378471Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.390739ms"} {"level":"info","ts":"2026-03-31T18:26:20.38981Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6894f7e0c0041bce","cluster-id":"db11c089a44de10a"} {"level":"info","ts":"2026-03-31T18:26:20.389918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T18:26:20.389946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 0"} {"level":"info","ts":"2026-03-31T18:26:20.389969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6894f7e0c0041bce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T18:26:20.389984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 1"} {"level":"info","ts":"2026-03-31T18:26:20.390033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"warn","ts":"2026-03-31T18:26:20.408867Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T18:26:20.410901Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T18:26:20.413072Z","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-03-31T18:26:20.41583Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6894f7e0c0041bce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T18:26:20.41601Z","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-03-31T18:26:20.416056Z","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-03-31T18:26:20.416067Z","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-03-31T18:26:20.416348Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6894f7e0c0041bce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T18:26:20.417308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"info","ts":"2026-03-31T18:26:20.418442Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","added-peer-id":"6894f7e0c0041bce","added-peer-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-03-31T18:26:20.420484Z","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-03-31T18:26:20.420579Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-03-31T18:26:20.420651Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-03-31T18:26:20.420866Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6894f7e0c0041bce","initial-advertise-peer-urls":["https://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T18:26:20.420919Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T18:26:20.79136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T18:26:20.791436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T18:26:20.791565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgPreVoteResp from 6894f7e0c0041bce at term 1"} {"level":"info","ts":"2026-03-31T18:26:20.79159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became candidate at term 2"} {"level":"info","ts":"2026-03-31T18:26:20.791598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgVoteResp from 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-03-31T18:26:20.791609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became leader at term 2"} {"level":"info","ts":"2026-03-31T18:26:20.791619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6894f7e0c0041bce elected leader 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-03-31T18:26:20.792471Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T18:26:20.794032Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T18:26:20.794097Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6894f7e0c0041bce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.124:2379]}","request-path":"/0/members/6894f7e0c0041bce/attributes","cluster-id":"db11c089a44de10a","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T18:26:20.794472Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T18:26:20.794549Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T18:26:20.796461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T18:26:20.801933Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T18:26:20.802033Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T18:26:20.802077Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T18:26:20.802553Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T18:26:20.810983Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.124:2379"} {"level":"warn","ts":"2026-03-31T18:26:24.861163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.026273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:26:24.86131Z","caller":"traceutil/trace.go:171","msg":"trace[1023635993] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"416.43124ms","start":"2026-03-31T18:26:24.444867Z","end":"2026-03-31T18:26:24.861299Z","steps":["trace[1023635993] 'process raft request' (duration: 416.393929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:24.861359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:24.444846Z","time spent":"416.496232ms","remote":"127.0.0.1:44448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T18:26:24.861419Z","caller":"traceutil/trace.go:171","msg":"trace[1147012973] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"669.461436ms","start":"2026-03-31T18:26:24.191928Z","end":"2026-03-31T18:26:24.861389Z","steps":["trace[1147012973] 'process raft request' (duration: 450.89264ms)","trace[1147012973] 'compare' (duration: 217.90899ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:26:24.861727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:24.191911Z","time spent":"669.63573ms","remote":"127.0.0.1:44534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T18:26:27.510903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.171869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:26:27.511047Z","caller":"traceutil/trace.go:171","msg":"trace[645715641] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"642.793326ms","start":"2026-03-31T18:26:26.868231Z","end":"2026-03-31T18:26:27.511024Z","steps":["trace[645715641] 'process raft request' (duration: 240.419903ms)","trace[645715641] 'compare' (duration: 401.904573ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:26:27.511129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:26.868204Z","time spent":"642.892329ms","remote":"127.0.0.1:44660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T18:26:28.710313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093987274s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:26:28.710449Z","caller":"traceutil/trace.go:171","msg":"trace[1721349074] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:286; }","duration":"1.763669315s","start":"2026-03-31T18:26:26.946756Z","end":"2026-03-31T18:26:28.710426Z","steps":["trace[1721349074] 'read index received' (duration: 161.90931ms)","trace[1721349074] 'applied index is now lower than readState.Index' (duration: 1.601758255s)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:26:28.710702Z","caller":"traceutil/trace.go:171","msg":"trace[2045400773] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"1.793596359s","start":"2026-03-31T18:26:26.917092Z","end":"2026-03-31T18:26:28.710688Z","steps":["trace[2045400773] 'process raft request' (duration: 699.131774ms)","trace[2045400773] 'compare' (duration: 1.093862381s)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:26:28.710766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:26.91706Z","time spent":"1.793678941s","remote":"127.0.0.1:44534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T18:26:28.71097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764209057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:26:28.71101Z","caller":"traceutil/trace.go:171","msg":"trace[1811476072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:279; }","duration":"1.764287959s","start":"2026-03-31T18:26:26.946711Z","end":"2026-03-31T18:26:28.710999Z","steps":["trace[1811476072] 'agreement among raft nodes before linearized reading' (duration: 1.764204137s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:28.711082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:26.946697Z","time spent":"1.764375431s","remote":"127.0.0.1:44336","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-03-31T18:26:28.985111Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.368814194s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T18:26:29.040036Z","caller":"traceutil/trace.go:171","msg":"trace[1874862146] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:288; }","duration":"329.506611ms","start":"2026-03-31T18:26:28.710504Z","end":"2026-03-31T18:26:29.04001Z","steps":["trace[1874862146] 'read index received' (duration: 274.78706ms)","trace[1874862146] 'applied index is now lower than readState.Index' (duration: 54.718501ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:26:29.04015Z","caller":"traceutil/trace.go:171","msg":"trace[1165333319] transaction","detail":"{read_only:false; number_of_response:0; response_revision:279; }","duration":"1.544253428s","start":"2026-03-31T18:26:27.495864Z","end":"2026-03-31T18:26:29.040117Z","steps":["trace[1165333319] 'process raft request' (duration: 1.489477887s)","trace[1165333319] 'compare' (duration: 54.564326ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:26:29.040199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.296381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T18:26:29.040233Z","caller":"traceutil/trace.go:171","msg":"trace[1112204580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:279; }","duration":"849.398723ms","start":"2026-03-31T18:26:28.190824Z","end":"2026-03-31T18:26:29.040223Z","steps":["trace[1112204580] 'agreement among raft nodes before linearized reading' (duration: 849.316861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:29.040175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.866206ms","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-03-31T18:26:29.04031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.41221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T18:26:29.040323Z","caller":"traceutil/trace.go:171","msg":"trace[157829272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:279; }","duration":"618.060581ms","start":"2026-03-31T18:26:28.422241Z","end":"2026-03-31T18:26:29.040301Z","steps":["trace[157829272] 'agreement among raft nodes before linearized reading' (duration: 617.817365ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:26:29.040335Z","caller":"traceutil/trace.go:171","msg":"trace[853444707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:279; }","duration":"876.474981ms","start":"2026-03-31T18:26:28.163852Z","end":"2026-03-31T18:26:29.040327Z","steps":["trace[853444707] 'agreement among raft nodes before linearized reading' (duration: 876.43518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:29.04035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546076111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-03-31T18:26:29.04036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:28.163828Z","time spent":"876.527013ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T18:26:29.040376Z","caller":"traceutil/trace.go:171","msg":"trace[938002689] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:279; }","duration":"1.546138942s","start":"2026-03-31T18:26:27.494229Z","end":"2026-03-31T18:26:29.040368Z","steps":["trace[938002689] 'agreement among raft nodes before linearized reading' (duration: 1.546067481s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:29.040266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:28.190793Z","time spent":"849.465565ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T18:26:29.040402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:27.49421Z","time spent":"1.546187663s","remote":"127.0.0.1:44516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-03-31T18:26:29.040506Z","caller":"traceutil/trace.go:171","msg":"trace[1340149657] transaction","detail":"{read_only:false; number_of_response:0; response_revision:279; }","duration":"1.544084994s","start":"2026-03-31T18:26:27.496412Z","end":"2026-03-31T18:26:29.040497Z","steps":["trace[1340149657] 'process raft request' (duration: 1.543558842s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:29.040569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:27.496391Z","time spent":"1.544138106s","remote":"127.0.0.1:44516","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T18:26:29.040267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:27.495843Z","time spent":"1.544372401s","remote":"127.0.0.1:44516","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T18:26:29.040656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.75663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:26:29.040688Z","caller":"traceutil/trace.go:171","msg":"trace[906876841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:279; }","duration":"324.816821ms","start":"2026-03-31T18:26:28.715863Z","end":"2026-03-31T18:26:29.040679Z","steps":["trace[906876841] 'agreement among raft nodes before linearized reading' (duration: 324.76882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:29.040713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:28.715846Z","time spent":"324.861502ms","remote":"127.0.0.1:44340","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-03-31T18:26:29.040742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192967981s","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-03-31T18:26:29.04078Z","caller":"traceutil/trace.go:171","msg":"trace[718613289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:279; }","duration":"1.193042263s","start":"2026-03-31T18:26:27.847729Z","end":"2026-03-31T18:26:29.040771Z","steps":["trace[718613289] 'agreement among raft nodes before linearized reading' (duration: 1.192976321s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:29.040806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:27.847708Z","time spent":"1.193091213s","remote":"127.0.0.1:44594","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-03-31T18:26:29.040371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:28.422223Z","time spent":"618.137573ms","remote":"127.0.0.1:44594","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-03-31T18:26:29.040859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.77273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-31T18:26:29.040903Z","caller":"traceutil/trace.go:171","msg":"trace[2020492690] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:279; }","duration":"324.848152ms","start":"2026-03-31T18:26:28.716041Z","end":"2026-03-31T18:26:29.040889Z","steps":["trace[2020492690] 'agreement among raft nodes before linearized reading' (duration: 324.781551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:29.041002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:28.716025Z","time spent":"324.905843ms","remote":"127.0.0.1:44534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"info","ts":"2026-03-31T18:26:30.287385Z","caller":"traceutil/trace.go:171","msg":"trace[24780526] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"104.239221ms","start":"2026-03-31T18:26:30.183118Z","end":"2026-03-31T18:26:30.287357Z","steps":["trace[24780526] 'read index received' (duration: 13.898313ms)","trace[24780526] 'applied index is now lower than readState.Index' (duration: 90.339888ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:26:30.287517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.382804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-31T18:26:30.287551Z","caller":"traceutil/trace.go:171","msg":"trace[2146798838] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:346; }","duration":"104.468476ms","start":"2026-03-31T18:26:30.183072Z","end":"2026-03-31T18:26:30.287541Z","steps":["trace[2146798838] 'agreement among raft nodes before linearized reading' (duration: 104.382554ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:26:30.287478Z","caller":"traceutil/trace.go:171","msg":"trace[1951969931] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"150.829753ms","start":"2026-03-31T18:26:30.136603Z","end":"2026-03-31T18:26:30.287433Z","steps":["trace[1951969931] 'process raft request' (duration: 60.476645ms)","trace[1951969931] 'compare' (duration: 90.151463ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:26:43.074686Z","caller":"traceutil/trace.go:171","msg":"trace[2042766524] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"105.839337ms","start":"2026-03-31T18:26:42.96882Z","end":"2026-03-31T18:26:43.074659Z","steps":["trace[2042766524] 'process raft request' (duration: 105.689844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:54.911243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.757789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:26:54.911517Z","caller":"traceutil/trace.go:171","msg":"trace[1410161685] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"546.775508ms","start":"2026-03-31T18:26:54.364711Z","end":"2026-03-31T18:26:54.911486Z","steps":["trace[1410161685] 'process raft request' (duration: 191.385314ms)","trace[1410161685] 'compare' (duration: 354.503853ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:26:54.911661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:54.364693Z","time spent":"546.89563ms","remote":"127.0.0.1:44398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T18:26:54.911656Z","caller":"traceutil/trace.go:171","msg":"trace[914634693] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"511.068439ms","start":"2026-03-31T18:26:54.400499Z","end":"2026-03-31T18:26:54.911567Z","steps":["trace[914634693] 'process raft request' (duration: 510.960186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:26:54.9118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:26:54.400477Z","time spent":"511.287704ms","remote":"127.0.0.1:44398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T18:26:55.848912Z","caller":"traceutil/trace.go:171","msg":"trace[1249100725] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"262.208278ms","start":"2026-03-31T18:26:55.586672Z","end":"2026-03-31T18:26:55.848881Z","steps":["trace[1249100725] 'process raft request' (duration: 261.601154ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:26:57.09558Z","caller":"traceutil/trace.go:171","msg":"trace[293285815] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"137.141615ms","start":"2026-03-31T18:26:56.958416Z","end":"2026-03-31T18:26:57.095557Z","steps":["trace[293285815] 'process raft request' (duration: 136.95402ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:01.523069Z","caller":"traceutil/trace.go:171","msg":"trace[181863302] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"169.636822ms","start":"2026-03-31T18:27:01.353408Z","end":"2026-03-31T18:27:01.523045Z","steps":["trace[181863302] 'read index received' (duration: 168.855464ms)","trace[181863302] 'applied index is now lower than readState.Index' (duration: 780.388µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:27:01.523914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.490941ms","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-03-31T18:27:01.523983Z","caller":"traceutil/trace.go:171","msg":"trace[1908973944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"170.606704ms","start":"2026-03-31T18:27:01.35336Z","end":"2026-03-31T18:27:01.523967Z","steps":["trace[1908973944] 'agreement among raft nodes before linearized reading' (duration: 170.455941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:01.523943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.300737ms","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-03-31T18:27:01.524224Z","caller":"traceutil/trace.go:171","msg":"trace[1726737578] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"628.043848ms","start":"2026-03-31T18:27:00.89617Z","end":"2026-03-31T18:27:01.524214Z","steps":["trace[1726737578] 'process raft request' (duration: 626.319838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:01.524294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:00.89614Z","time spent":"628.10733ms","remote":"127.0.0.1:44438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T18:27:01.524126Z","caller":"traceutil/trace.go:171","msg":"trace[2055830956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"164.504472ms","start":"2026-03-31T18:27:01.3596Z","end":"2026-03-31T18:27:01.524105Z","steps":["trace[2055830956] 'agreement among raft nodes before linearized reading' (duration: 164.284687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:01.524944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.070869ms","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-03-31T18:27:01.524973Z","caller":"traceutil/trace.go:171","msg":"trace[880344811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"148.12674ms","start":"2026-03-31T18:27:01.376838Z","end":"2026-03-31T18:27:01.524965Z","steps":["trace[880344811] 'agreement among raft nodes before linearized reading' (duration: 148.027638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:01.525069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.997957ms","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-03-31T18:27:01.525092Z","caller":"traceutil/trace.go:171","msg":"trace[1595730257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"163.042878ms","start":"2026-03-31T18:27:01.362043Z","end":"2026-03-31T18:27:01.525086Z","steps":["trace[1595730257] 'agreement among raft nodes before linearized reading' (duration: 163.004247ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:02.250553Z","caller":"traceutil/trace.go:171","msg":"trace[1386781684] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"223.633958ms","start":"2026-03-31T18:27:02.026883Z","end":"2026-03-31T18:27:02.250517Z","steps":["trace[1386781684] 'process raft request' (duration: 223.236069ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:02.818288Z","caller":"traceutil/trace.go:171","msg":"trace[1880808270] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"238.854353ms","start":"2026-03-31T18:27:02.579409Z","end":"2026-03-31T18:27:02.818264Z","steps":["trace[1880808270] 'read index received' (duration: 238.672459ms)","trace[1880808270] 'applied index is now lower than readState.Index' (duration: 181.154µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:27:02.818509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.082748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gzmt2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-31T18:27:02.818538Z","caller":"traceutil/trace.go:171","msg":"trace[133258275] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gzmt2; range_end:; response_count:1; response_revision:593; }","duration":"239.16286ms","start":"2026-03-31T18:27:02.579367Z","end":"2026-03-31T18:27:02.81853Z","steps":["trace[133258275] 'agreement among raft nodes before linearized reading' (duration: 238.975096ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:02.818796Z","caller":"traceutil/trace.go:171","msg":"trace[16221077] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"511.630008ms","start":"2026-03-31T18:27:02.30713Z","end":"2026-03-31T18:27:02.81876Z","steps":["trace[16221077] 'process raft request' (duration: 510.999743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:02.818896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:02.307098Z","time spent":"511.74098ms","remote":"127.0.0.1:44842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T18:27:04.497151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527407048s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:27:04.497312Z","caller":"traceutil/trace.go:171","msg":"trace[555083245] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"1.548594012s","start":"2026-03-31T18:27:02.948661Z","end":"2026-03-31T18:27:04.497255Z","steps":["trace[555083245] 'read index received' (duration: 20.899677ms)","trace[555083245] 'applied index is now lower than readState.Index' (duration: 1.527692725s)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:27:04.497399Z","caller":"traceutil/trace.go:171","msg":"trace[169205649] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.6601707s","start":"2026-03-31T18:27:02.837217Z","end":"2026-03-31T18:27:04.497388Z","steps":["trace[169205649] 'process raft request' (duration: 132.440294ms)","trace[169205649] 'compare' (duration: 1.527276405s)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:27:04.497472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:02.837197Z","time spent":"1.660231402s","remote":"127.0.0.1:44516","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-03-31T18:27:04.497477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548792416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:27:04.497545Z","caller":"traceutil/trace.go:171","msg":"trace[1664648713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"1.548891438s","start":"2026-03-31T18:27:02.948634Z","end":"2026-03-31T18:27:04.497525Z","steps":["trace[1664648713] 'agreement among raft nodes before linearized reading' (duration: 1.548777145s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:04.497573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:02.94862Z","time spent":"1.5489482s","remote":"127.0.0.1:44336","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-03-31T18:27:04.803908Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.513082135s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T18:27:04.805447Z","caller":"traceutil/trace.go:171","msg":"trace[321315229] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.516889983s","start":"2026-03-31T18:27:03.288536Z","end":"2026-03-31T18:27:04.805425Z","steps":["trace[321315229] 'process raft request' (duration: 1.516092485s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:04.805729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:03.288512Z","time spent":"1.516993455s","remote":"127.0.0.1:44438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T18:27:04.818983Z","caller":"traceutil/trace.go:171","msg":"trace[841191782] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"321.604141ms","start":"2026-03-31T18:27:04.497355Z","end":"2026-03-31T18:27:04.818959Z","steps":["trace[841191782] 'read index received' (duration: 306.915389ms)","trace[841191782] 'applied index is now lower than readState.Index' (duration: 14.687742ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:27:04.819244Z","caller":"traceutil/trace.go:171","msg":"trace[2078161076] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.278517991s","start":"2026-03-31T18:27:03.540715Z","end":"2026-03-31T18:27:04.819233Z","steps":["trace[2078161076] 'process raft request' (duration: 1.278126792s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:04.819339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:03.540695Z","time spent":"1.278574922s","remote":"127.0.0.1:44594","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-03-31T18:27:04.819619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.084698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T18:27:04.819663Z","caller":"traceutil/trace.go:171","msg":"trace[1374954984] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:596; }","duration":"773.20558ms","start":"2026-03-31T18:27:04.046447Z","end":"2026-03-31T18:27:04.819652Z","steps":["trace[1374954984] 'agreement among raft nodes before linearized reading' (duration: 773.093568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:04.819745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:04.046424Z","time spent":"773.309563ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.124\" "} {"level":"warn","ts":"2026-03-31T18:27:04.820002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284996811s","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-03-31T18:27:04.820039Z","caller":"traceutil/trace.go:171","msg":"trace[605859887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"1.285045292s","start":"2026-03-31T18:27:03.534983Z","end":"2026-03-31T18:27:04.820028Z","steps":["trace[605859887] 'agreement among raft nodes before linearized reading' (duration: 1.28493628s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:04.820129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:03.534976Z","time spent":"1.285143554s","remote":"127.0.0.1:44594","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-03-31T18:27:04.820222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285648837s","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-03-31T18:27:04.820226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285588285s","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-03-31T18:27:04.82027Z","caller":"traceutil/trace.go:171","msg":"trace[921913122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"1.285736989s","start":"2026-03-31T18:27:03.534524Z","end":"2026-03-31T18:27:04.820261Z","steps":["trace[921913122] 'agreement among raft nodes before linearized reading' (duration: 1.285624406s)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:04.820287Z","caller":"traceutil/trace.go:171","msg":"trace[409831388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"1.285672237s","start":"2026-03-31T18:27:03.5346Z","end":"2026-03-31T18:27:04.820272Z","steps":["trace[409831388] 'agreement among raft nodes before linearized reading' (duration: 1.285464382s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:04.820296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:03.534507Z","time spent":"1.285782869s","remote":"127.0.0.1:44594","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-03-31T18:27:04.820329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:03.534594Z","time spent":"1.285726758s","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T18:27:04.820128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.13225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T18:27:04.820433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28577542s","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-03-31T18:27:04.820456Z","caller":"traceutil/trace.go:171","msg":"trace[1250579576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"1.28583081s","start":"2026-03-31T18:27:03.534618Z","end":"2026-03-31T18:27:04.820449Z","steps":["trace[1250579576] 'agreement among raft nodes before linearized reading' (duration: 1.285752868s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:04.821677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:03.534597Z","time spent":"1.28706977s","remote":"127.0.0.1:44594","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-03-31T18:27:04.82046Z","caller":"traceutil/trace.go:171","msg":"trace[685881583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"321.488649ms","start":"2026-03-31T18:27:04.498954Z","end":"2026-03-31T18:27:04.820443Z","steps":["trace[685881583] 'agreement among raft nodes before linearized reading' (duration: 321.145831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:04.821785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:04.498938Z","time spent":"322.83507ms","remote":"127.0.0.1:44340","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-03-31T18:27:10.056238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.413622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:27:10.05637Z","caller":"traceutil/trace.go:171","msg":"trace[655352078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"110.596836ms","start":"2026-03-31T18:27:09.945744Z","end":"2026-03-31T18:27:10.056341Z","steps":["trace[655352078] 'range keys from in-memory index tree' (duration: 110.34501ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:16.849044Z","caller":"traceutil/trace.go:171","msg":"trace[1318801228] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"125.135824ms","start":"2026-03-31T18:27:16.723886Z","end":"2026-03-31T18:27:16.849022Z","steps":["trace[1318801228] 'process raft request' (duration: 122.470522ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:17.041436Z","caller":"traceutil/trace.go:171","msg":"trace[1738682399] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"133.979541ms","start":"2026-03-31T18:27:16.907429Z","end":"2026-03-31T18:27:17.041409Z","steps":["trace[1738682399] 'read index received' (duration: 55.716258ms)","trace[1738682399] 'applied index is now lower than readState.Index' (duration: 78.262273ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:27:17.041502Z","caller":"traceutil/trace.go:171","msg":"trace[944570882] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"142.684653ms","start":"2026-03-31T18:27:16.898783Z","end":"2026-03-31T18:27:17.041468Z","steps":["trace[944570882] 'process raft request' (duration: 64.522653ms)","trace[944570882] 'compare' (duration: 77.982186ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:27:17.041676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.224366ms","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-03-31T18:27:17.041747Z","caller":"traceutil/trace.go:171","msg":"trace[254277615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:730; }","duration":"134.309048ms","start":"2026-03-31T18:27:16.907418Z","end":"2026-03-31T18:27:17.041727Z","steps":["trace[254277615] 'agreement among raft nodes before linearized reading' (duration: 134.129134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:17.042011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.468632ms","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-03-31T18:27:17.042046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.503673ms","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-03-31T18:27:17.042126Z","caller":"traceutil/trace.go:171","msg":"trace[2044310175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"134.585925ms","start":"2026-03-31T18:27:16.907486Z","end":"2026-03-31T18:27:17.042072Z","steps":["trace[2044310175] 'agreement among raft nodes before linearized reading' (duration: 134.3582ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:17.04214Z","caller":"traceutil/trace.go:171","msg":"trace[902790281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:730; }","duration":"134.611925ms","start":"2026-03-31T18:27:16.907506Z","end":"2026-03-31T18:27:17.042118Z","steps":["trace[902790281] 'agreement among raft nodes before linearized reading' (duration: 134.38572ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:21.998994Z","caller":"traceutil/trace.go:171","msg":"trace[2143019002] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"300.387347ms","start":"2026-03-31T18:27:21.698563Z","end":"2026-03-31T18:27:21.99895Z","steps":["trace[2143019002] 'process raft request' (duration: 300.168722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:21.999264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:21.698536Z","time spent":"300.606602ms","remote":"127.0.0.1:44570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T18:27:26.013634Z","caller":"traceutil/trace.go:171","msg":"trace[1199158094] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"252.626195ms","start":"2026-03-31T18:27:25.760971Z","end":"2026-03-31T18:27:26.013598Z","steps":["trace[1199158094] 'process raft request' (duration: 199.352293ms)","trace[1199158094] 'compare' (duration: 53.023226ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:27:26.227269Z","caller":"traceutil/trace.go:171","msg":"trace[1991501894] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"206.639862ms","start":"2026-03-31T18:27:26.020611Z","end":"2026-03-31T18:27:26.227251Z","steps":["trace[1991501894] 'process raft request' (duration: 206.510989ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:26.690345Z","caller":"traceutil/trace.go:171","msg":"trace[205742348] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"206.913779ms","start":"2026-03-31T18:27:26.483413Z","end":"2026-03-31T18:27:26.690326Z","steps":["trace[205742348] 'process raft request' (duration: 206.382447ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:26.740176Z","caller":"traceutil/trace.go:171","msg":"trace[700680098] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:820; }","duration":"230.584791ms","start":"2026-03-31T18:27:26.509576Z","end":"2026-03-31T18:27:26.740161Z","steps":["trace[700680098] 'read index received' (duration: 180.644128ms)","trace[700680098] 'applied index is now lower than readState.Index' (duration: 49.940043ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:27:26.740258Z","caller":"traceutil/trace.go:171","msg":"trace[2117083320] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"256.806151ms","start":"2026-03-31T18:27:26.483432Z","end":"2026-03-31T18:27:26.740238Z","steps":["trace[2117083320] 'process raft request' (duration: 256.645608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:26.740318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.731955ms","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-03-31T18:27:26.740343Z","caller":"traceutil/trace.go:171","msg":"trace[1571908830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"230.798107ms","start":"2026-03-31T18:27:26.509539Z","end":"2026-03-31T18:27:26.740338Z","steps":["trace[1571908830] 'agreement among raft nodes before linearized reading' (duration: 230.680924ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:26.907634Z","caller":"traceutil/trace.go:171","msg":"trace[2134380808] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"138.386484ms","start":"2026-03-31T18:27:26.769217Z","end":"2026-03-31T18:27:26.907604Z","steps":["trace[2134380808] 'process raft request' (duration: 101.463924ms)","trace[2134380808] 'compare' (duration: 36.676784ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:27:29.119436Z","caller":"traceutil/trace.go:171","msg":"trace[1735364260] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"108.949148ms","start":"2026-03-31T18:27:29.010458Z","end":"2026-03-31T18:27:29.119407Z","steps":["trace[1735364260] 'process raft request' (duration: 108.341234ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:27:58.815083Z","caller":"traceutil/trace.go:171","msg":"trace[2087673428] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"437.838119ms","start":"2026-03-31T18:27:58.377214Z","end":"2026-03-31T18:27:58.815052Z","steps":["trace[2087673428] 'process raft request' (duration: 437.683845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:58.815313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:58.377181Z","time spent":"438.057843ms","remote":"127.0.0.1:44594","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-03-31T18:27:59.716231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.769356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-31T18:27:59.716291Z","caller":"traceutil/trace.go:171","msg":"trace[2063399115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:984; }","duration":"701.856818ms","start":"2026-03-31T18:27:59.014419Z","end":"2026-03-31T18:27:59.716276Z","steps":["trace[2063399115] 'range keys from in-memory index tree' (duration: 701.583912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:59.716317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:59.014401Z","time spent":"701.90937ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T18:27:59.716296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.661784ms","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-03-31T18:27:59.716358Z","caller":"traceutil/trace.go:171","msg":"trace[817503180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"361.767037ms","start":"2026-03-31T18:27:59.354576Z","end":"2026-03-31T18:27:59.716343Z","steps":["trace[817503180] 'range keys from in-memory index tree' (duration: 361.424339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:59.716386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:59.354556Z","time spent":"361.823578ms","remote":"127.0.0.1:44594","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-03-31T18:27:59.716233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.650393ms","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-03-31T18:27:59.716394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.180866ms","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-03-31T18:27:59.716442Z","caller":"traceutil/trace.go:171","msg":"trace[1255274286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"376.893179ms","start":"2026-03-31T18:27:59.339534Z","end":"2026-03-31T18:27:59.716427Z","steps":["trace[1255274286] 'range keys from in-memory index tree' (duration: 376.397757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:59.716442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.390876ms","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-03-31T18:27:59.716457Z","caller":"traceutil/trace.go:171","msg":"trace[556004843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"304.255257ms","start":"2026-03-31T18:27:59.412177Z","end":"2026-03-31T18:27:59.716433Z","steps":["trace[556004843] 'range keys from in-memory index tree' (duration: 304.065813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:59.716478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:59.339513Z","time spent":"376.955801ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T18:27:59.716491Z","caller":"traceutil/trace.go:171","msg":"trace[1814255728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:984; }","duration":"330.509589ms","start":"2026-03-31T18:27:59.385968Z","end":"2026-03-31T18:27:59.716478Z","steps":["trace[1814255728] 'range keys from in-memory index tree' (duration: 330.192081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:59.716502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:59.412166Z","time spent":"304.325888ms","remote":"127.0.0.1:44594","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-03-31T18:27:59.71653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:59.385948Z","time spent":"330.57163ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T18:27:59.716624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.74954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:27:59.716654Z","caller":"traceutil/trace.go:171","msg":"trace[1416502130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"767.649541ms","start":"2026-03-31T18:27:58.948997Z","end":"2026-03-31T18:27:59.716646Z","steps":["trace[1416502130] 'range keys from in-memory index tree' (duration: 766.677488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:27:59.716724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:27:58.948981Z","time spent":"767.737413ms","remote":"127.0.0.1:44336","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-03-31T18:28:09.939633Z","caller":"traceutil/trace.go:171","msg":"trace[1168607069] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"128.309194ms","start":"2026-03-31T18:28:09.811304Z","end":"2026-03-31T18:28:09.939613Z","steps":["trace[1168607069] 'process raft request' (duration: 128.167491ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:28:09.93987Z","caller":"traceutil/trace.go:171","msg":"trace[1499322811] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"128.55016ms","start":"2026-03-31T18:28:09.811306Z","end":"2026-03-31T18:28:09.939856Z","steps":["trace[1499322811] 'process raft request' (duration: 128.272394ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:28:09.939989Z","caller":"traceutil/trace.go:171","msg":"trace[1066765086] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"125.408097ms","start":"2026-03-31T18:28:09.814571Z","end":"2026-03-31T18:28:09.939979Z","steps":["trace[1066765086] 'process raft request' (duration: 125.231233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:24.387637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711804534320928,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T18:28:24.46173Z","caller":"traceutil/trace.go:171","msg":"trace[150460761] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"589.497398ms","start":"2026-03-31T18:28:23.872209Z","end":"2026-03-31T18:28:24.461707Z","steps":["trace[150460761] 'process raft request' (duration: 589.321024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:24.461919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:23.872191Z","time spent":"589.63025ms","remote":"127.0.0.1:44594","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-03-31T18:28:24.597376Z","caller":"traceutil/trace.go:171","msg":"trace[1446338492] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1104; }","duration":"709.836335ms","start":"2026-03-31T18:28:23.887521Z","end":"2026-03-31T18:28:24.597357Z","steps":["trace[1446338492] 'read index received' (duration: 574.053797ms)","trace[1446338492] 'applied index is now lower than readState.Index' (duration: 135.781818ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:28:24.597606Z","caller":"traceutil/trace.go:171","msg":"trace[2103453652] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"591.239568ms","start":"2026-03-31T18:28:24.006345Z","end":"2026-03-31T18:28:24.597585Z","steps":["trace[2103453652] 'process raft request' (duration: 551.819048ms)","trace[2103453652] 'compare' (duration: 39.121453ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:28:24.597812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.290306ms","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-03-31T18:28:24.597837Z","caller":"traceutil/trace.go:171","msg":"trace[1114758779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1064; }","duration":"710.348687ms","start":"2026-03-31T18:28:23.887481Z","end":"2026-03-31T18:28:24.59783Z","steps":["trace[1114758779] 'agreement among raft nodes before linearized reading' (duration: 710.234955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:24.597835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.397748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-31T18:28:24.597854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:23.887464Z","time spent":"710.385489ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T18:28:24.597845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.34031ms","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-03-31T18:28:24.597877Z","caller":"traceutil/trace.go:171","msg":"trace[616551362] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:1064; }","duration":"548.46998ms","start":"2026-03-31T18:28:24.049393Z","end":"2026-03-31T18:28:24.597863Z","steps":["trace[616551362] 'agreement among raft nodes before linearized reading' (duration: 548.328487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:24.597917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:24.049377Z","time spent":"548.532871ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.124\" "} {"level":"info","ts":"2026-03-31T18:28:24.59792Z","caller":"traceutil/trace.go:171","msg":"trace[2290480] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1064; }","duration":"495.475003ms","start":"2026-03-31T18:28:24.102428Z","end":"2026-03-31T18:28:24.597903Z","steps":["trace[2290480] 'agreement among raft nodes before linearized reading' (duration: 495.312689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:24.597964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:24.102407Z","time spent":"495.547235ms","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-03-31T18:28:24.597975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.331984ms","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-03-31T18:28:24.597863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.131089ms","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-03-31T18:28:24.598026Z","caller":"traceutil/trace.go:171","msg":"trace[725524974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1064; }","duration":"583.404256ms","start":"2026-03-31T18:28:24.014606Z","end":"2026-03-31T18:28:24.59801Z","steps":["trace[725524974] 'agreement among raft nodes before linearized reading' (duration: 583.289193ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:28:24.598146Z","caller":"traceutil/trace.go:171","msg":"trace[1924723377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"589.371784ms","start":"2026-03-31T18:28:24.008715Z","end":"2026-03-31T18:28:24.598086Z","steps":["trace[1924723377] 'agreement among raft nodes before linearized reading' (duration: 589.003846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:24.598135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:24.014586Z","time spent":"583.534448ms","remote":"127.0.0.1:44594","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-03-31T18:28:24.598173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.189003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-31T18:28:24.598241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:24.008679Z","time spent":"589.524838ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T18:28:24.598291Z","caller":"traceutil/trace.go:171","msg":"trace[1953429365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1064; }","duration":"578.343717ms","start":"2026-03-31T18:28:24.019927Z","end":"2026-03-31T18:28:24.598271Z","steps":["trace[1953429365] 'agreement among raft nodes before linearized reading' (duration: 577.935517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:24.598084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:24.006325Z","time spent":"591.29993ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T18:28:24.59839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:24.019888Z","time spent":"578.48673ms","remote":"127.0.0.1:44594","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-03-31T18:28:24.826983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.027638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-31T18:28:24.827077Z","caller":"traceutil/trace.go:171","msg":"trace[1683352923] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1069; }","duration":"128.167291ms","start":"2026-03-31T18:28:24.698891Z","end":"2026-03-31T18:28:24.827059Z","steps":["trace[1683352923] 'range keys from in-memory index tree' (duration: 127.915056ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:28:26.552808Z","caller":"traceutil/trace.go:171","msg":"trace[856853221] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"228.942953ms","start":"2026-03-31T18:28:26.323846Z","end":"2026-03-31T18:28:26.552789Z","steps":["trace[856853221] 'process raft request' (duration: 228.309688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:27.14038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.510692ms","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-03-31T18:28:27.140496Z","caller":"traceutil/trace.go:171","msg":"trace[378652499] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1071; }","duration":"117.639525ms","start":"2026-03-31T18:28:27.022841Z","end":"2026-03-31T18:28:27.14048Z","steps":["trace[378652499] 'range keys from in-memory index tree' (duration: 117.461221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:27.140962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.173628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:28:27.141021Z","caller":"traceutil/trace.go:171","msg":"trace[292078129] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"455.209322ms","start":"2026-03-31T18:28:26.685801Z","end":"2026-03-31T18:28:27.14101Z","steps":["trace[292078129] 'read index received' (duration: 39.921972ms)","trace[292078129] 'applied index is now lower than readState.Index' (duration: 415.28616ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:28:27.141122Z","caller":"traceutil/trace.go:171","msg":"trace[499112814] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"537.976574ms","start":"2026-03-31T18:28:26.603117Z","end":"2026-03-31T18:28:27.141094Z","steps":["trace[499112814] 'process raft request' (duration: 122.609272ms)","trace[499112814] 'compare' (duration: 414.773649ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:28:27.141218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.414537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-31T18:28:27.141247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:26.603098Z","time spent":"538.079776ms","remote":"127.0.0.1:44594","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-03-31T18:28:27.141265Z","caller":"traceutil/trace.go:171","msg":"trace[1350757560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1072; }","duration":"455.51286ms","start":"2026-03-31T18:28:26.685738Z","end":"2026-03-31T18:28:27.141251Z","steps":["trace[1350757560] 'agreement among raft nodes before linearized reading' (duration: 455.361436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:27.14132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:26.685721Z","time spent":"455.589541ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T18:28:27.141427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.328325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-31T18:28:27.141481Z","caller":"traceutil/trace.go:171","msg":"trace[73214191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1072; }","duration":"455.395057ms","start":"2026-03-31T18:28:26.686073Z","end":"2026-03-31T18:28:27.141468Z","steps":["trace[73214191] 'agreement among raft nodes before linearized reading' (duration: 455.270884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:27.141515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:26.686067Z","time spent":"455.439498ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T18:28:27.141528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.389246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T18:28:27.141571Z","caller":"traceutil/trace.go:171","msg":"trace[1873483307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1072; }","duration":"455.439648ms","start":"2026-03-31T18:28:26.686121Z","end":"2026-03-31T18:28:27.141561Z","steps":["trace[1873483307] 'agreement among raft nodes before linearized reading' (duration: 455.321535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:27.141602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:26.686116Z","time spent":"455.480999ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T18:28:27.141569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.391407ms","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-03-31T18:28:27.141648Z","caller":"traceutil/trace.go:171","msg":"trace[681572296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1072; }","duration":"455.489379ms","start":"2026-03-31T18:28:26.686138Z","end":"2026-03-31T18:28:27.141627Z","steps":["trace[681572296] 'agreement among raft nodes before linearized reading' (duration: 455.247963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:27.14173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:26.686135Z","time spent":"455.56715ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T18:28:27.141898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.613932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T18:28:27.142204Z","caller":"traceutil/trace.go:171","msg":"trace[766815076] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1072; }","duration":"110.827726ms","start":"2026-03-31T18:28:27.031226Z","end":"2026-03-31T18:28:27.142054Z","steps":["trace[766815076] 'agreement among raft nodes before linearized reading' (duration: 110.207391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:27.392872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.167411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:28:27.393069Z","caller":"traceutil/trace.go:171","msg":"trace[1472514575] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"247.665599ms","start":"2026-03-31T18:28:27.145386Z","end":"2026-03-31T18:28:27.393052Z","steps":["trace[1472514575] 'process raft request' (duration: 247.581187ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:28:27.393072Z","caller":"traceutil/trace.go:171","msg":"trace[785477889] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"247.724501ms","start":"2026-03-31T18:28:27.145321Z","end":"2026-03-31T18:28:27.393046Z","steps":["trace[785477889] 'process raft request' (duration: 95.303704ms)","trace[785477889] 'compare' (duration: 152.038498ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:28:27.393196Z","caller":"traceutil/trace.go:171","msg":"trace[378510173] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"247.767743ms","start":"2026-03-31T18:28:27.145419Z","end":"2026-03-31T18:28:27.393186Z","steps":["trace[378510173] 'process raft request' (duration: 247.599159ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:28:27.393172Z","caller":"traceutil/trace.go:171","msg":"trace[1846696780] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1116; }","duration":"188.702083ms","start":"2026-03-31T18:28:27.204436Z","end":"2026-03-31T18:28:27.393138Z","steps":["trace[1846696780] 'read index received' (duration: 36.141443ms)","trace[1846696780] 'applied index is now lower than readState.Index' (duration: 152.55939ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:28:27.393316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.850177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:28:27.393373Z","caller":"traceutil/trace.go:171","msg":"trace[323477650] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1076; }","duration":"188.97234ms","start":"2026-03-31T18:28:27.204387Z","end":"2026-03-31T18:28:27.393359Z","steps":["trace[323477650] 'agreement among raft nodes before linearized reading' (duration: 188.860707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:29.622077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.579399ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T18:28:29.622482Z","caller":"traceutil/trace.go:171","msg":"trace[208288690] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"468.873952ms","start":"2026-03-31T18:28:29.153586Z","end":"2026-03-31T18:28:29.62246Z","steps":["trace[208288690] 'read index received' (duration: 127.878714ms)","trace[208288690] 'applied index is now lower than readState.Index' (duration: 340.994188ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:28:29.622602Z","caller":"traceutil/trace.go:171","msg":"trace[1815165507] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"475.904526ms","start":"2026-03-31T18:28:29.146684Z","end":"2026-03-31T18:28:29.622588Z","steps":["trace[1815165507] 'process raft request' (duration: 475.511407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:29.62272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:29.146659Z","time spent":"475.976948ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T18:28:29.622854Z","caller":"traceutil/trace.go:171","msg":"trace[688550744] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"361.929427ms","start":"2026-03-31T18:28:29.260905Z","end":"2026-03-31T18:28:29.622835Z","steps":["trace[688550744] 'process raft request' (duration: 361.454266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:29.622938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:29.260886Z","time spent":"362.008378ms","remote":"127.0.0.1:44594","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-03-31T18:28:29.623044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.153028ms","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-03-31T18:28:29.623086Z","caller":"traceutil/trace.go:171","msg":"trace[1959304117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1079; }","duration":"226.23833ms","start":"2026-03-31T18:28:29.396836Z","end":"2026-03-31T18:28:29.623074Z","steps":["trace[1959304117] 'agreement among raft nodes before linearized reading' (duration: 226.106337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:29.623121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.785016ms","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-03-31T18:28:29.623238Z","caller":"traceutil/trace.go:171","msg":"trace[1752539813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"224.893768ms","start":"2026-03-31T18:28:29.398303Z","end":"2026-03-31T18:28:29.623197Z","steps":["trace[1752539813] 'agreement among raft nodes before linearized reading' (duration: 224.721344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:29.623315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.670207ms","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-03-31T18:28:29.623362Z","caller":"traceutil/trace.go:171","msg":"trace[1781368569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1079; }","duration":"225.750559ms","start":"2026-03-31T18:28:29.397601Z","end":"2026-03-31T18:28:29.623352Z","steps":["trace[1781368569] 'agreement among raft nodes before linearized reading' (duration: 225.646186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:29.62362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.015649ms","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-03-31T18:28:29.623719Z","caller":"traceutil/trace.go:171","msg":"trace[1525286229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"470.176703ms","start":"2026-03-31T18:28:29.15352Z","end":"2026-03-31T18:28:29.623696Z","steps":["trace[1525286229] 'agreement among raft nodes before linearized reading' (duration: 469.19246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:29.623772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:29.153492Z","time spent":"470.270855ms","remote":"127.0.0.1:44594","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-03-31T18:28:41.8986Z","caller":"traceutil/trace.go:171","msg":"trace[816041637] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"147.880771ms","start":"2026-03-31T18:28:41.750683Z","end":"2026-03-31T18:28:41.898564Z","steps":["trace[816041637] 'process raft request' (duration: 147.759418ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:28:41.898726Z","caller":"traceutil/trace.go:171","msg":"trace[597050914] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1218; }","duration":"144.84206ms","start":"2026-03-31T18:28:41.753864Z","end":"2026-03-31T18:28:41.898706Z","steps":["trace[597050914] 'read index received' (duration: 81.768788ms)","trace[597050914] 'applied index is now lower than readState.Index' (duration: 63.072642ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:28:41.89879Z","caller":"traceutil/trace.go:171","msg":"trace[1235570039] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"148.731531ms","start":"2026-03-31T18:28:41.750029Z","end":"2026-03-31T18:28:41.89876Z","steps":["trace[1235570039] 'process raft request' (duration: 85.534256ms)","trace[1235570039] 'compare' (duration: 62.467958ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:28:41.898806Z","caller":"traceutil/trace.go:171","msg":"trace[2084089937] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"147.670726ms","start":"2026-03-31T18:28:41.751118Z","end":"2026-03-31T18:28:41.898789Z","steps":["trace[2084089937] 'process raft request' (duration: 147.40861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:41.89888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.999674ms","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-03-31T18:28:41.89892Z","caller":"traceutil/trace.go:171","msg":"trace[1576286113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1173; }","duration":"145.074106ms","start":"2026-03-31T18:28:41.753834Z","end":"2026-03-31T18:28:41.898908Z","steps":["trace[1576286113] 'agreement among raft nodes before linearized reading' (duration: 144.950533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:43.356779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.996749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:28:43.356883Z","caller":"traceutil/trace.go:171","msg":"trace[181023629] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"932.608615ms","start":"2026-03-31T18:28:42.424258Z","end":"2026-03-31T18:28:43.356866Z","steps":["trace[181023629] 'read index received' (duration: 453.391991ms)","trace[181023629] 'applied index is now lower than readState.Index' (duration: 479.215414ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:28:43.357097Z","caller":"traceutil/trace.go:171","msg":"trace[559303065] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"1.454029993s","start":"2026-03-31T18:28:41.90305Z","end":"2026-03-31T18:28:43.35708Z","steps":["trace[559303065] 'process raft request' (duration: 974.649215ms)","trace[559303065] 'compare' (duration: 478.821555ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:28:43.357192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:41.903022Z","time spent":"1.454112995s","remote":"127.0.0.1:44594","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-03-31T18:28:43.35721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.920152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T18:28:43.357232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.544368ms","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-03-31T18:28:43.357291Z","caller":"traceutil/trace.go:171","msg":"trace[689457944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1174; }","duration":"628.648291ms","start":"2026-03-31T18:28:42.728629Z","end":"2026-03-31T18:28:43.357277Z","steps":["trace[689457944] 'agreement among raft nodes before linearized reading' (duration: 628.476647ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:28:43.357287Z","caller":"traceutil/trace.go:171","msg":"trace[79762583] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1174; }","duration":"933.044404ms","start":"2026-03-31T18:28:42.424225Z","end":"2026-03-31T18:28:43.35727Z","steps":["trace[79762583] 'agreement among raft nodes before linearized reading' (duration: 932.892161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:43.357321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:42.728604Z","time spent":"628.710232ms","remote":"127.0.0.1:44594","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-03-31T18:28:43.357338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:42.424211Z","time spent":"933.111447ms","remote":"127.0.0.1:44688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-31T18:28:43.357413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.722002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:28:43.357433Z","caller":"traceutil/trace.go:171","msg":"trace[135158378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"409.764733ms","start":"2026-03-31T18:28:42.947661Z","end":"2026-03-31T18:28:43.357426Z","steps":["trace[135158378] 'agreement among raft nodes before linearized reading' (duration: 409.736512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:43.35745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:42.947646Z","time spent":"409.801244ms","remote":"127.0.0.1:44336","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-03-31T18:28:59.932886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711804534321329,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T18:28:59.958302Z","caller":"traceutil/trace.go:171","msg":"trace[1316715111] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"529.923037ms","start":"2026-03-31T18:28:59.428361Z","end":"2026-03-31T18:28:59.958284Z","steps":["trace[1316715111] 'process raft request' (duration: 529.561209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:28:59.958415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:59.428343Z","time spent":"530.013489ms","remote":"127.0.0.1:44594","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-03-31T18:29:00.181214Z","caller":"traceutil/trace.go:171","msg":"trace[1528313711] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1287; }","duration":"748.935089ms","start":"2026-03-31T18:28:59.432258Z","end":"2026-03-31T18:29:00.181193Z","steps":["trace[1528313711] 'read index received' (duration: 526.541848ms)","trace[1528313711] 'applied index is now lower than readState.Index' (duration: 222.392331ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:00.181362Z","caller":"traceutil/trace.go:171","msg":"trace[335355658] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"423.903893ms","start":"2026-03-31T18:28:59.757415Z","end":"2026-03-31T18:29:00.181319Z","steps":["trace[335355658] 'process raft request' (duration: 423.703978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:00.181433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.156764ms","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-03-31T18:29:00.18145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.040518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:29:00.181465Z","caller":"traceutil/trace.go:171","msg":"trace[417647769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1237; }","duration":"749.225565ms","start":"2026-03-31T18:28:59.43223Z","end":"2026-03-31T18:29:00.181455Z","steps":["trace[417647769] 'agreement among raft nodes before linearized reading' (duration: 749.082542ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:00.181493Z","caller":"traceutil/trace.go:171","msg":"trace[945457701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"233.124331ms","start":"2026-03-31T18:28:59.948358Z","end":"2026-03-31T18:29:00.181482Z","steps":["trace[945457701] 'agreement among raft nodes before linearized reading' (duration: 233.033158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:00.181499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:59.432218Z","time spent":"749.274497ms","remote":"127.0.0.1:44594","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-03-31T18:29:00.181521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:59.757391Z","time spent":"424.027796ms","remote":"127.0.0.1:44594","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-03-31T18:29:00.181542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.567531ms","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-03-31T18:29:00.181558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.151214ms","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-03-31T18:29:00.18158Z","caller":"traceutil/trace.go:171","msg":"trace[877225824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"191.621772ms","start":"2026-03-31T18:28:59.989948Z","end":"2026-03-31T18:29:00.18157Z","steps":["trace[877225824] 'agreement among raft nodes before linearized reading' (duration: 191.492059ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:00.181603Z","caller":"traceutil/trace.go:171","msg":"trace[1725564046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1237; }","duration":"192.216406ms","start":"2026-03-31T18:28:59.989373Z","end":"2026-03-31T18:29:00.181589Z","steps":["trace[1725564046] 'agreement among raft nodes before linearized reading' (duration: 192.049682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:00.181648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.714937ms","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-03-31T18:29:00.181663Z","caller":"traceutil/trace.go:171","msg":"trace[1868534721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1237; }","duration":"198.761359ms","start":"2026-03-31T18:28:59.982897Z","end":"2026-03-31T18:29:00.181658Z","steps":["trace[1868534721] 'agreement among raft nodes before linearized reading' (duration: 198.712918ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:00.181665Z","caller":"traceutil/trace.go:171","msg":"trace[985852734] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"520.487077ms","start":"2026-03-31T18:28:59.661163Z","end":"2026-03-31T18:29:00.18165Z","steps":["trace[985852734] 'process raft request' (duration: 472.280122ms)","trace[985852734] 'compare' (duration: 47.286554ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:00.181721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:28:59.661145Z","time spent":"520.547398ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T18:29:02.05554Z","caller":"traceutil/trace.go:171","msg":"trace[1734759047] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"102.315838ms","start":"2026-03-31T18:29:01.953183Z","end":"2026-03-31T18:29:02.055499Z","steps":["trace[1734759047] 'process raft request' (duration: 102.164194ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:02.360878Z","caller":"traceutil/trace.go:171","msg":"trace[100799026] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"173.929349ms","start":"2026-03-31T18:29:02.186924Z","end":"2026-03-31T18:29:02.360853Z","steps":["trace[100799026] 'process raft request' (duration: 173.750655ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:02.361088Z","caller":"traceutil/trace.go:171","msg":"trace[2020873155] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"133.813682ms","start":"2026-03-31T18:29:02.227003Z","end":"2026-03-31T18:29:02.360817Z","steps":["trace[2020873155] 'read index received' (duration: 133.657599ms)","trace[2020873155] 'applied index is now lower than readState.Index' (duration: 155.223µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:02.361195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.177032ms","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-03-31T18:29:02.361232Z","caller":"traceutil/trace.go:171","msg":"trace[1151548233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"134.254743ms","start":"2026-03-31T18:29:02.226969Z","end":"2026-03-31T18:29:02.361223Z","steps":["trace[1151548233] 'agreement among raft nodes before linearized reading' (duration: 134.12926ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:02.602192Z","caller":"traceutil/trace.go:171","msg":"trace[313453636] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"236.97547ms","start":"2026-03-31T18:29:02.365196Z","end":"2026-03-31T18:29:02.602171Z","steps":["trace[313453636] 'process raft request' (duration: 198.654375ms)","trace[313453636] 'compare' (duration: 38.240483ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:02.868752Z","caller":"traceutil/trace.go:171","msg":"trace[1488015762] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"502.747412ms","start":"2026-03-31T18:29:02.365979Z","end":"2026-03-31T18:29:02.868727Z","steps":["trace[1488015762] 'process raft request' (duration: 502.66676ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:02.868854Z","caller":"traceutil/trace.go:171","msg":"trace[698138256] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1299; }","duration":"329.954689ms","start":"2026-03-31T18:29:02.538871Z","end":"2026-03-31T18:29:02.868825Z","steps":["trace[698138256] 'read index received' (duration: 24.996883ms)","trace[698138256] 'applied index is now lower than readState.Index' (duration: 304.957116ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:02.868937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:02.36597Z","time spent":"502.854784ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T18:29:02.869031Z","caller":"traceutil/trace.go:171","msg":"trace[355059393] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"501.700148ms","start":"2026-03-31T18:29:02.367323Z","end":"2026-03-31T18:29:02.869023Z","steps":["trace[355059393] 'process raft request' (duration: 501.36532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:02.869116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:02.367307Z","time spent":"501.745628ms","remote":"127.0.0.1:44594","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-03-31T18:29:02.869158Z","caller":"traceutil/trace.go:171","msg":"trace[1487161577] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"503.234623ms","start":"2026-03-31T18:29:02.365898Z","end":"2026-03-31T18:29:02.869133Z","steps":["trace[1487161577] 'process raft request' (duration: 499.448965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:02.869222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.348438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T18:29:02.869246Z","caller":"traceutil/trace.go:171","msg":"trace[1708732721] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1251; }","duration":"330.40107ms","start":"2026-03-31T18:29:02.538839Z","end":"2026-03-31T18:29:02.86924Z","steps":["trace[1708732721] 'agreement among raft nodes before linearized reading' (duration: 330.350738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:02.869267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:02.538823Z","time spent":"330.43785ms","remote":"127.0.0.1:44458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-31T18:29:02.869293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:02.365883Z","time spent":"503.342215ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T18:29:02.869442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.410052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-03-31T18:29:02.869463Z","caller":"traceutil/trace.go:171","msg":"trace[106498613] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1251; }","duration":"208.461524ms","start":"2026-03-31T18:29:02.660996Z","end":"2026-03-31T18:29:02.869457Z","steps":["trace[106498613] 'agreement among raft nodes before linearized reading' (duration: 208.383372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:03.339688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.2395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:03.339779Z","caller":"traceutil/trace.go:171","msg":"trace[1595589714] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"391.846924ms","start":"2026-03-31T18:29:02.947917Z","end":"2026-03-31T18:29:03.339764Z","steps":["trace[1595589714] 'read index received' (duration: 174.021691ms)","trace[1595589714] 'applied index is now lower than readState.Index' (duration: 217.823743ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:03.339851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.936977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:29:03.339883Z","caller":"traceutil/trace.go:171","msg":"trace[2112402855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"391.992458ms","start":"2026-03-31T18:29:02.947881Z","end":"2026-03-31T18:29:03.339873Z","steps":["trace[2112402855] 'agreement among raft nodes before linearized reading' (duration: 391.931326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:03.339912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:02.947863Z","time spent":"392.04015ms","remote":"127.0.0.1:44336","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-03-31T18:29:03.339899Z","caller":"traceutil/trace.go:171","msg":"trace[2048085860] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"460.63627ms","start":"2026-03-31T18:29:02.879235Z","end":"2026-03-31T18:29:03.339871Z","steps":["trace[2048085860] 'process raft request' (duration: 242.765186ms)","trace[2048085860] 'compare' (duration: 216.965973ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:03.340042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:02.879217Z","time spent":"460.742822ms","remote":"127.0.0.1:44514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T18:29:03.91324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.340813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:03.913322Z","caller":"traceutil/trace.go:171","msg":"trace[1519128782] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"567.31514ms","start":"2026-03-31T18:29:03.345991Z","end":"2026-03-31T18:29:03.913306Z","steps":["trace[1519128782] 'process raft request' (duration: 154.839843ms)","trace[1519128782] 'compare' (duration: 411.637927ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:03.913363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:03.345979Z","time spent":"567.36525ms","remote":"127.0.0.1:44710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T18:29:15.015502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711804534321528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T18:29:15.515615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711804534321528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T18:29:15.744063Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.322767384s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T18:29:15.744396Z","caller":"traceutil/trace.go:171","msg":"trace[193237752] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"1.228922151s","start":"2026-03-31T18:29:14.515377Z","end":"2026-03-31T18:29:15.744299Z","steps":["trace[193237752] 'read index received' (duration: 1.228774887s)","trace[193237752] 'applied index is now lower than readState.Index' (duration: 146.634µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:15.74451Z","caller":"traceutil/trace.go:171","msg":"trace[180129034] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.323234335s","start":"2026-03-31T18:29:14.4212Z","end":"2026-03-31T18:29:15.744434Z","steps":["trace[180129034] 'process raft request' (duration: 1.322990989s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.744675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:14.421176Z","time spent":"1.323421579s","remote":"127.0.0.1:44594","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-03-31T18:29:15.744753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085183072s","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-03-31T18:29:15.744786Z","caller":"traceutil/trace.go:171","msg":"trace[868129919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"1.085269833s","start":"2026-03-31T18:29:14.659507Z","end":"2026-03-31T18:29:15.744777Z","steps":["trace[868129919] 'agreement among raft nodes before linearized reading' (duration: 1.08515379s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.744823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:14.659488Z","time spent":"1.085328555s","remote":"127.0.0.1:44594","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-03-31T18:29:15.744947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.168671ms","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-03-31T18:29:15.744963Z","caller":"traceutil/trace.go:171","msg":"trace[1475790245] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1340; }","duration":"118.222202ms","start":"2026-03-31T18:29:15.626736Z","end":"2026-03-31T18:29:15.744959Z","steps":["trace[1475790245] 'agreement among raft nodes before linearized reading' (duration: 118.170411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.745026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.531246ms","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-03-31T18:29:15.745038Z","caller":"traceutil/trace.go:171","msg":"trace[105967711] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1340; }","duration":"194.587457ms","start":"2026-03-31T18:29:15.550447Z","end":"2026-03-31T18:29:15.745034Z","steps":["trace[105967711] 'agreement among raft nodes before linearized reading' (duration: 194.549766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.745086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.05327ms","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-03-31T18:29:15.745103Z","caller":"traceutil/trace.go:171","msg":"trace[1653289452] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1340; }","duration":"225.110662ms","start":"2026-03-31T18:29:15.519988Z","end":"2026-03-31T18:29:15.745098Z","steps":["trace[1653289452] 'agreement among raft nodes before linearized reading' (duration: 225.086131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.74523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.550298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:29:15.745249Z","caller":"traceutil/trace.go:171","msg":"trace[688559972] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1340; }","duration":"248.571409ms","start":"2026-03-31T18:29:15.496673Z","end":"2026-03-31T18:29:15.745244Z","steps":["trace[688559972] 'agreement among raft nodes before linearized reading' (duration: 248.543198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.745364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.504005ms","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-03-31T18:29:15.745385Z","caller":"traceutil/trace.go:171","msg":"trace[223435407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"808.544426ms","start":"2026-03-31T18:29:14.936837Z","end":"2026-03-31T18:29:15.745381Z","steps":["trace[223435407] 'agreement among raft nodes before linearized reading' (duration: 808.492065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.745398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:14.936828Z","time spent":"808.566106ms","remote":"127.0.0.1:44594","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-03-31T18:29:15.745492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.027491ms","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-03-31T18:29:15.745528Z","caller":"traceutil/trace.go:171","msg":"trace[348521898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1340; }","duration":"819.099213ms","start":"2026-03-31T18:29:14.926419Z","end":"2026-03-31T18:29:15.745519Z","steps":["trace[348521898] 'agreement among raft nodes before linearized reading' (duration: 818.97313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.745537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.690033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-31T18:29:15.745541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23016209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-31T18:29:15.745607Z","caller":"traceutil/trace.go:171","msg":"trace[2108405021] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1340; }","duration":"1.230266852s","start":"2026-03-31T18:29:14.51533Z","end":"2026-03-31T18:29:15.745596Z","steps":["trace[2108405021] 'agreement among raft nodes before linearized reading' (duration: 1.229146646s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.745633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:14.51531Z","time spent":"1.230316603s","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-31T18:29:15.745555Z","caller":"traceutil/trace.go:171","msg":"trace[214877989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"809.742014ms","start":"2026-03-31T18:29:14.935807Z","end":"2026-03-31T18:29:15.745549Z","steps":["trace[214877989] 'agreement among raft nodes before linearized reading' (duration: 809.699113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:15.745745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:14.935786Z","time spent":"809.931869ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T18:29:15.745551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:14.926401Z","time spent":"819.145644ms","remote":"127.0.0.1:44594","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-03-31T18:29:16.549135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.074463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:16.549211Z","caller":"traceutil/trace.go:171","msg":"trace[2051246633] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"801.037261ms","start":"2026-03-31T18:29:15.74816Z","end":"2026-03-31T18:29:16.549197Z","steps":["trace[2051246633] 'process raft request' (duration: 307.837105ms)","trace[2051246633] 'compare' (duration: 492.841088ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:16.549253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:15.748134Z","time spent":"801.098713ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T18:29:16.551314Z","caller":"traceutil/trace.go:171","msg":"trace[1854074164] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"802.170577ms","start":"2026-03-31T18:29:15.749128Z","end":"2026-03-31T18:29:16.551299Z","steps":["trace[1854074164] 'process raft request' (duration: 802.116856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:16.551437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:15.749123Z","time spent":"802.250159ms","remote":"127.0.0.1:44594","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-03-31T18:29:16.551567Z","caller":"traceutil/trace.go:171","msg":"trace[1505729052] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"802.590997ms","start":"2026-03-31T18:29:15.748971Z","end":"2026-03-31T18:29:16.551562Z","steps":["trace[1505729052] 'process raft request' (duration: 802.176738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:16.551604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:15.748952Z","time spent":"802.632678ms","remote":"127.0.0.1:44594","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-03-31T18:29:16.55188Z","caller":"traceutil/trace.go:171","msg":"trace[417137682] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"802.100206ms","start":"2026-03-31T18:29:15.749774Z","end":"2026-03-31T18:29:16.551874Z","steps":["trace[417137682] 'process raft request' (duration: 801.86053ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:16.551812Z","caller":"traceutil/trace.go:171","msg":"trace[2032556426] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"802.367812ms","start":"2026-03-31T18:29:15.749414Z","end":"2026-03-31T18:29:16.551782Z","steps":["trace[2032556426] 'process raft request' (duration: 801.858651ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:16.551997Z","caller":"traceutil/trace.go:171","msg":"trace[1348387362] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"801.889321ms","start":"2026-03-31T18:29:15.750103Z","end":"2026-03-31T18:29:16.551992Z","steps":["trace[1348387362] 'process raft request' (duration: 801.746918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:16.552031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:15.750094Z","time spent":"801.920412ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T18:29:16.552164Z","caller":"traceutil/trace.go:171","msg":"trace[619186213] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1395; }","duration":"605.283676ms","start":"2026-03-31T18:29:15.94686Z","end":"2026-03-31T18:29:16.552144Z","steps":["trace[619186213] 'read index received' (duration: 109.09926ms)","trace[619186213] 'applied index is now lower than readState.Index' (duration: 496.180726ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:16.55226Z","caller":"traceutil/trace.go:171","msg":"trace[1663260124] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"802.006804ms","start":"2026-03-31T18:29:15.750248Z","end":"2026-03-31T18:29:16.552255Z","steps":["trace[1663260124] 'process raft request' (duration: 801.725177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:16.552299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:15.750218Z","time spent":"802.063755ms","remote":"127.0.0.1:44594","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-03-31T18:29:16.551914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:15.749747Z","time spent":"802.149707ms","remote":"127.0.0.1:44594","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-03-31T18:29:16.552535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.675195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:29:16.552659Z","caller":"traceutil/trace.go:171","msg":"trace[1070601380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"605.824659ms","start":"2026-03-31T18:29:15.946824Z","end":"2026-03-31T18:29:16.552648Z","steps":["trace[1070601380] 'agreement among raft nodes before linearized reading' (duration: 605.654445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:16.552736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:15.946808Z","time spent":"605.919501ms","remote":"127.0.0.1:44336","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-03-31T18:29:16.553196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.72895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-31T18:29:16.553301Z","caller":"traceutil/trace.go:171","msg":"trace[763578757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"363.868314ms","start":"2026-03-31T18:29:16.189422Z","end":"2026-03-31T18:29:16.55329Z","steps":["trace[763578757] 'agreement among raft nodes before linearized reading' (duration: 363.683089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:16.553866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:16.189401Z","time spent":"364.456098ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T18:29:16.553193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.468492ms","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-03-31T18:29:16.554371Z","caller":"traceutil/trace.go:171","msg":"trace[158031565] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1347; }","duration":"105.69636ms","start":"2026-03-31T18:29:16.448663Z","end":"2026-03-31T18:29:16.55436Z","steps":["trace[158031565] 'agreement among raft nodes before linearized reading' (duration: 104.465331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:16.554823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:15.749405Z","time spent":"805.382783ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T18:29:22.046594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.180436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:22.046779Z","caller":"traceutil/trace.go:171","msg":"trace[1036239555] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"164.7083ms","start":"2026-03-31T18:29:21.882038Z","end":"2026-03-31T18:29:22.046746Z","steps":["trace[1036239555] 'process raft request' (duration: 33.298988ms)","trace[1036239555] 'compare' (duration: 131.000452ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:22.800949Z","caller":"traceutil/trace.go:171","msg":"trace[1761073225] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"167.477444ms","start":"2026-03-31T18:29:22.633445Z","end":"2026-03-31T18:29:22.800922Z","steps":["trace[1761073225] 'process raft request' (duration: 167.383932ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:22.801159Z","caller":"traceutil/trace.go:171","msg":"trace[89338642] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"167.563496ms","start":"2026-03-31T18:29:22.633535Z","end":"2026-03-31T18:29:22.801098Z","steps":["trace[89338642] 'process raft request' (duration: 167.34082ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:22.800979Z","caller":"traceutil/trace.go:171","msg":"trace[187026661] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"167.690129ms","start":"2026-03-31T18:29:22.63325Z","end":"2026-03-31T18:29:22.80094Z","steps":["trace[187026661] 'process raft request' (duration: 144.740463ms)","trace[187026661] 'compare' (duration: 22.70138ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:33.117655Z","caller":"traceutil/trace.go:171","msg":"trace[1930850954] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"210.621683ms","start":"2026-03-31T18:29:32.907008Z","end":"2026-03-31T18:29:33.117629Z","steps":["trace[1930850954] 'process raft request' (duration: 146.879653ms)","trace[1930850954] 'compare' (duration: 63.664978ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:33.289028Z","caller":"traceutil/trace.go:171","msg":"trace[263496677] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"381.945986ms","start":"2026-03-31T18:29:32.907061Z","end":"2026-03-31T18:29:33.289007Z","steps":["trace[263496677] 'process raft request' (duration: 379.034438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:33.289149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:32.907036Z","time spent":"382.051399ms","remote":"127.0.0.1:44594","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-03-31T18:29:33.289134Z","caller":"traceutil/trace.go:171","msg":"trace[737637161] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1478; }","duration":"341.185724ms","start":"2026-03-31T18:29:32.947912Z","end":"2026-03-31T18:29:33.289098Z","steps":["trace[737637161] 'read index received' (duration: 106.064059ms)","trace[737637161] 'applied index is now lower than readState.Index' (duration: 235.119965ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:33.289243Z","caller":"traceutil/trace.go:171","msg":"trace[85400617] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"381.902046ms","start":"2026-03-31T18:29:32.907312Z","end":"2026-03-31T18:29:33.289214Z","steps":["trace[85400617] 'process raft request' (duration: 381.62517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:33.289306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.377499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:29:33.289375Z","caller":"traceutil/trace.go:171","msg":"trace[230713520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1423; }","duration":"341.486771ms","start":"2026-03-31T18:29:32.947871Z","end":"2026-03-31T18:29:33.289358Z","steps":["trace[230713520] 'agreement among raft nodes before linearized reading' (duration: 341.381709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:33.289385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:32.907304Z","time spent":"381.998278ms","remote":"127.0.0.1:44594","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-03-31T18:29:33.289414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:32.947851Z","time spent":"341.555093ms","remote":"127.0.0.1:44336","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-03-31T18:29:33.289665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.518027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T18:29:33.289707Z","caller":"traceutil/trace.go:171","msg":"trace[936367111] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1423; }","duration":"111.607108ms","start":"2026-03-31T18:29:33.178089Z","end":"2026-03-31T18:29:33.289696Z","steps":["trace[936367111] 'agreement among raft nodes before linearized reading' (duration: 111.456875ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:35.566866Z","caller":"traceutil/trace.go:171","msg":"trace[2026201434] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"271.056895ms","start":"2026-03-31T18:29:35.295785Z","end":"2026-03-31T18:29:35.566842Z","steps":["trace[2026201434] 'process raft request' (duration: 270.910102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:35.830835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.827536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:35.830936Z","caller":"traceutil/trace.go:171","msg":"trace[1105395967] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"534.765209ms","start":"2026-03-31T18:29:35.296151Z","end":"2026-03-31T18:29:35.830916Z","steps":["trace[1105395967] 'process raft request' (duration: 362.785429ms)","trace[1105395967] 'compare' (duration: 171.693973ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:35.830992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:35.29614Z","time spent":"534.82587ms","remote":"127.0.0.1:44594","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-03-31T18:29:36.119757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.93055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:36.119876Z","caller":"traceutil/trace.go:171","msg":"trace[1135171260] linearizableReadLoop","detail":"{readStateIndex:1492; appliedIndex:1491; }","duration":"173.720831ms","start":"2026-03-31T18:29:35.946135Z","end":"2026-03-31T18:29:36.119856Z","steps":["trace[1135171260] 'read index received' (duration: 27.540324ms)","trace[1135171260] 'applied index is now lower than readState.Index' (duration: 146.178737ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:36.119937Z","caller":"traceutil/trace.go:171","msg":"trace[947366987] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"192.333755ms","start":"2026-03-31T18:29:35.927582Z","end":"2026-03-31T18:29:36.119915Z","steps":["trace[947366987] 'process raft request' (duration: 46.154609ms)","trace[947366987] 'compare' (duration: 145.814948ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:36.119952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.822913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:29:36.119992Z","caller":"traceutil/trace.go:171","msg":"trace[418223380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1433; }","duration":"173.900115ms","start":"2026-03-31T18:29:35.94608Z","end":"2026-03-31T18:29:36.119981Z","steps":["trace[418223380] 'agreement among raft nodes before linearized reading' (duration: 173.833363ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:36.742366Z","caller":"traceutil/trace.go:171","msg":"trace[1641090522] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"119.202576ms","start":"2026-03-31T18:29:36.623131Z","end":"2026-03-31T18:29:36.742334Z","steps":["trace[1641090522] 'process raft request' (duration: 118.926909ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:37.082876Z","caller":"traceutil/trace.go:171","msg":"trace[680438859] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"102.488645ms","start":"2026-03-31T18:29:36.980342Z","end":"2026-03-31T18:29:37.08283Z","steps":["trace[680438859] 'process raft request' (duration: 40.994768ms)","trace[680438859] 'compare' (duration: 61.279782ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:37.29334Z","caller":"traceutil/trace.go:171","msg":"trace[837518590] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"155.083154ms","start":"2026-03-31T18:29:37.138231Z","end":"2026-03-31T18:29:37.293314Z","steps":["trace[837518590] 'process raft request' (duration: 154.920901ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:38.477221Z","caller":"traceutil/trace.go:171","msg":"trace[1764668154] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"139.339636ms","start":"2026-03-31T18:29:38.337861Z","end":"2026-03-31T18:29:38.477201Z","steps":["trace[1764668154] 'process raft request' (duration: 139.224774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:39.195064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.885167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:39.195169Z","caller":"traceutil/trace.go:171","msg":"trace[1718329295] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1505; }","duration":"108.083775ms","start":"2026-03-31T18:29:39.087068Z","end":"2026-03-31T18:29:39.195152Z","steps":["trace[1718329295] 'read index received' (duration: 47.011µs)","trace[1718329295] 'applied index is now lower than readState.Index' (duration: 108.035464ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:39.195276Z","caller":"traceutil/trace.go:171","msg":"trace[1281622762] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"212.846034ms","start":"2026-03-31T18:29:38.982353Z","end":"2026-03-31T18:29:39.195199Z","steps":["trace[1281622762] 'process raft request' (duration: 16.747432ms)","trace[1281622762] 'compare' (duration: 195.57944ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:39.195303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.2443ms","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-03-31T18:29:39.195492Z","caller":"traceutil/trace.go:171","msg":"trace[288844354] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1447; }","duration":"108.447764ms","start":"2026-03-31T18:29:39.087011Z","end":"2026-03-31T18:29:39.195458Z","steps":["trace[288844354] 'agreement among raft nodes before linearized reading' (duration: 108.190628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:39.419817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.492628ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T18:29:39.41991Z","caller":"traceutil/trace.go:171","msg":"trace[1564472383] linearizableReadLoop","detail":"{readStateIndex:1508; appliedIndex:1507; }","duration":"122.936884ms","start":"2026-03-31T18:29:39.296954Z","end":"2026-03-31T18:29:39.419891Z","steps":["trace[1564472383] 'read index received' (duration: 29.001µs)","trace[1564472383] 'applied index is now lower than readState.Index' (duration: 122.906663ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:39.420078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.112608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T18:29:39.420132Z","caller":"traceutil/trace.go:171","msg":"trace[182451859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1448; }","duration":"123.20073ms","start":"2026-03-31T18:29:39.296915Z","end":"2026-03-31T18:29:39.420116Z","steps":["trace[182451859] 'agreement among raft nodes before linearized reading' (duration: 123.040887ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:40.194598Z","caller":"traceutil/trace.go:171","msg":"trace[1034627264] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"230.736103ms","start":"2026-03-31T18:29:39.963839Z","end":"2026-03-31T18:29:40.194575Z","steps":["trace[1034627264] 'process raft request' (duration: 230.583859ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:42.329293Z","caller":"traceutil/trace.go:171","msg":"trace[1462918034] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"123.878996ms","start":"2026-03-31T18:29:42.20539Z","end":"2026-03-31T18:29:42.329269Z","steps":["trace[1462918034] 'process raft request' (duration: 123.729033ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:44.234742Z","caller":"traceutil/trace.go:171","msg":"trace[1696551556] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"154.437969ms","start":"2026-03-31T18:29:44.080276Z","end":"2026-03-31T18:29:44.234714Z","steps":["trace[1696551556] 'process raft request' (duration: 61.62624ms)","trace[1696551556] 'compare' (duration: 92.693526ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:45.419842Z","caller":"traceutil/trace.go:171","msg":"trace[500232864] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"127.0794ms","start":"2026-03-31T18:29:45.292726Z","end":"2026-03-31T18:29:45.419805Z","steps":["trace[500232864] 'process raft request' (duration: 126.864585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:45.617341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.156702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:45.617484Z","caller":"traceutil/trace.go:171","msg":"trace[1506797773] linearizableReadLoop","detail":"{readStateIndex:1536; appliedIndex:1535; }","duration":"113.294098ms","start":"2026-03-31T18:29:45.504169Z","end":"2026-03-31T18:29:45.617463Z","steps":["trace[1506797773] 'read index received' (duration: 47.421µs)","trace[1506797773] 'applied index is now lower than readState.Index' (duration: 113.244777ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:45.617572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.398141ms","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":"info","ts":"2026-03-31T18:29:45.61761Z","caller":"traceutil/trace.go:171","msg":"trace[879626556] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1475; }","duration":"113.439982ms","start":"2026-03-31T18:29:45.504157Z","end":"2026-03-31T18:29:45.617597Z","steps":["trace[879626556] 'agreement among raft nodes before linearized reading' (duration: 113.36573ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:45.617601Z","caller":"traceutil/trace.go:171","msg":"trace[97492814] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"192.209012ms","start":"2026-03-31T18:29:45.425367Z","end":"2026-03-31T18:29:45.617576Z","steps":["trace[97492814] 'process raft request' (duration: 70.704882ms)","trace[97492814] 'compare' (duration: 121.023649ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:47.766034Z","caller":"traceutil/trace.go:171","msg":"trace[1382800150] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"141.231641ms","start":"2026-03-31T18:29:47.624773Z","end":"2026-03-31T18:29:47.766005Z","steps":["trace[1382800150] 'process raft request' (duration: 141.059827ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:48.197155Z","caller":"traceutil/trace.go:171","msg":"trace[1827106106] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"158.529125ms","start":"2026-03-31T18:29:48.0386Z","end":"2026-03-31T18:29:48.197129Z","steps":["trace[1827106106] 'process raft request' (duration: 158.371692ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:48.678659Z","caller":"traceutil/trace.go:171","msg":"trace[983006670] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"115.732565ms","start":"2026-03-31T18:29:48.562895Z","end":"2026-03-31T18:29:48.678627Z","steps":["trace[983006670] 'process raft request' (duration: 64.538309ms)","trace[983006670] 'compare' (duration: 51.073683ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:52.743862Z","caller":"traceutil/trace.go:171","msg":"trace[1678666161] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"158.966765ms","start":"2026-03-31T18:29:52.584843Z","end":"2026-03-31T18:29:52.74381Z","steps":["trace[1678666161] 'process raft request' (duration: 158.727329ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:55.332435Z","caller":"traceutil/trace.go:171","msg":"trace[1971238742] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"111.799832ms","start":"2026-03-31T18:29:55.22061Z","end":"2026-03-31T18:29:55.33241Z","steps":["trace[1971238742] 'process raft request' (duration: 111.659199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:56.147115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.301185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:29:56.147217Z","caller":"traceutil/trace.go:171","msg":"trace[1114937997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1520; }","duration":"198.49223ms","start":"2026-03-31T18:29:55.948703Z","end":"2026-03-31T18:29:56.147195Z","steps":["trace[1114937997] 'range keys from in-memory index tree' (duration: 198.142971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:56.147303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.791036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-31T18:29:56.147314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.265818ms","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-03-31T18:29:56.147445Z","caller":"traceutil/trace.go:171","msg":"trace[1548471323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1520; }","duration":"110.123144ms","start":"2026-03-31T18:29:56.03729Z","end":"2026-03-31T18:29:56.147413Z","steps":["trace[1548471323] 'range keys from in-memory index tree' (duration: 109.51581ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:56.147482Z","caller":"traceutil/trace.go:171","msg":"trace[886332150] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1520; }","duration":"143.555145ms","start":"2026-03-31T18:29:56.003891Z","end":"2026-03-31T18:29:56.147446Z","steps":["trace[886332150] 'range keys from in-memory index tree' (duration: 143.069973ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:56.313429Z","caller":"traceutil/trace.go:171","msg":"trace[2114898635] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"159.538999ms","start":"2026-03-31T18:29:56.153867Z","end":"2026-03-31T18:29:56.313406Z","steps":["trace[2114898635] 'process raft request' (duration: 159.385935ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:56.453177Z","caller":"traceutil/trace.go:171","msg":"trace[365932578] linearizableReadLoop","detail":"{readStateIndex:1585; appliedIndex:1583; }","duration":"144.332892ms","start":"2026-03-31T18:29:56.30881Z","end":"2026-03-31T18:29:56.453143Z","steps":["trace[365932578] 'read index received' (duration: 4.495345ms)","trace[365932578] 'applied index is now lower than readState.Index' (duration: 139.836137ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:56.453255Z","caller":"traceutil/trace.go:171","msg":"trace[283034904] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"298.566387ms","start":"2026-03-31T18:29:56.154662Z","end":"2026-03-31T18:29:56.453228Z","steps":["trace[283034904] 'process raft request' (duration: 205.201716ms)","trace[283034904] 'compare' (duration: 93.068544ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:56.453436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.607489ms","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-03-31T18:29:56.453468Z","caller":"traceutil/trace.go:171","msg":"trace[1516054583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1522; }","duration":"144.716331ms","start":"2026-03-31T18:29:56.308741Z","end":"2026-03-31T18:29:56.453458Z","steps":["trace[1516054583] 'agreement among raft nodes before linearized reading' (duration: 144.514556ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:56.901152Z","caller":"traceutil/trace.go:171","msg":"trace[664141676] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"110.967814ms","start":"2026-03-31T18:29:56.79016Z","end":"2026-03-31T18:29:56.901127Z","steps":["trace[664141676] 'process raft request' (duration: 110.82585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:57.446324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.263807ms","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-03-31T18:29:57.446402Z","caller":"traceutil/trace.go:171","msg":"trace[1307707402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1528; }","duration":"110.373619ms","start":"2026-03-31T18:29:57.336007Z","end":"2026-03-31T18:29:57.446381Z","steps":["trace[1307707402] 'range keys from in-memory index tree' (duration: 110.096993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:58.240779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.987541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:29:58.240861Z","caller":"traceutil/trace.go:171","msg":"trace[883178669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1530; }","duration":"294.111144ms","start":"2026-03-31T18:29:57.946731Z","end":"2026-03-31T18:29:58.240842Z","steps":["trace[883178669] 'range keys from in-memory index tree' (duration: 293.899449ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:58.542093Z","caller":"traceutil/trace.go:171","msg":"trace[686847273] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"219.97314ms","start":"2026-03-31T18:29:58.322094Z","end":"2026-03-31T18:29:58.542067Z","steps":["trace[686847273] 'process raft request' (duration: 219.749595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:58.844633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.315783ms","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-03-31T18:29:58.844723Z","caller":"traceutil/trace.go:171","msg":"trace[918728919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1531; }","duration":"349.483467ms","start":"2026-03-31T18:29:58.495217Z","end":"2026-03-31T18:29:58.8447Z","steps":["trace[918728919] 'agreement among raft nodes before linearized reading' (duration: 47.064789ms)","trace[918728919] 'range keys from in-memory index tree' (duration: 302.117211ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:58.844766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:29:58.495196Z","time spent":"349.56054ms","remote":"127.0.0.1:44594","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-03-31T18:29:58.844794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.257864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:58.844933Z","caller":"traceutil/trace.go:171","msg":"trace[1429693062] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"297.691817ms","start":"2026-03-31T18:29:58.547208Z","end":"2026-03-31T18:29:58.8449Z","steps":["trace[1429693062] 'process raft request' (duration: 158.263838ms)","trace[1429693062] 'compare' (duration: 139.079381ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:29:58.844968Z","caller":"traceutil/trace.go:171","msg":"trace[1354284172] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"215.454525ms","start":"2026-03-31T18:29:58.629499Z","end":"2026-03-31T18:29:58.844953Z","steps":["trace[1354284172] 'process raft request' (duration: 215.403344ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:58.845095Z","caller":"traceutil/trace.go:171","msg":"trace[502766287] linearizableReadLoop","detail":"{readStateIndex:1595; appliedIndex:1594; }","duration":"281.946609ms","start":"2026-03-31T18:29:58.563129Z","end":"2026-03-31T18:29:58.845076Z","steps":["trace[502766287] 'read index received' (duration: 142.326916ms)","trace[502766287] 'applied index is now lower than readState.Index' (duration: 139.618233ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:58.84523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.104973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-03-31T18:29:58.845264Z","caller":"traceutil/trace.go:171","msg":"trace[1227085204] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1533; }","duration":"282.172725ms","start":"2026-03-31T18:29:58.563081Z","end":"2026-03-31T18:29:58.845253Z","steps":["trace[1227085204] 'agreement among raft nodes before linearized reading' (duration: 282.072703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:58.847883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.184218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T18:29:58.84797Z","caller":"traceutil/trace.go:171","msg":"trace[411842190] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1534; }","duration":"200.317751ms","start":"2026-03-31T18:29:58.647624Z","end":"2026-03-31T18:29:58.847942Z","steps":["trace[411842190] 'agreement among raft nodes before linearized reading' (duration: 200.173588ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:59.119284Z","caller":"traceutil/trace.go:171","msg":"trace[484635779] linearizableReadLoop","detail":"{readStateIndex:1599; appliedIndex:1597; }","duration":"213.265554ms","start":"2026-03-31T18:29:58.905998Z","end":"2026-03-31T18:29:59.119263Z","steps":["trace[484635779] 'read index received' (duration: 5.639462ms)","trace[484635779] 'applied index is now lower than readState.Index' (duration: 207.625252ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:59.119523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.50907ms","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-03-31T18:29:59.119559Z","caller":"traceutil/trace.go:171","msg":"trace[1398323286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1536; }","duration":"213.595002ms","start":"2026-03-31T18:29:58.905952Z","end":"2026-03-31T18:29:59.119547Z","steps":["trace[1398323286] 'agreement among raft nodes before linearized reading' (duration: 213.433478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:59.11958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.817046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:29:59.119758Z","caller":"traceutil/trace.go:171","msg":"trace[887789485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1536; }","duration":"172.04477ms","start":"2026-03-31T18:29:58.947697Z","end":"2026-03-31T18:29:59.119741Z","steps":["trace[887789485] 'agreement among raft nodes before linearized reading' (duration: 171.790594ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:29:59.120023Z","caller":"traceutil/trace.go:171","msg":"trace[554002966] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"269.356325ms","start":"2026-03-31T18:29:58.850068Z","end":"2026-03-31T18:29:59.119425Z","steps":["trace[554002966] 'process raft request' (duration: 269.078058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:29:59.370187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.353665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:29:59.370479Z","caller":"traceutil/trace.go:171","msg":"trace[1415071421] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"239.731012ms","start":"2026-03-31T18:29:59.13071Z","end":"2026-03-31T18:29:59.370441Z","steps":["trace[1415071421] 'process raft request' (duration: 132.993008ms)","trace[1415071421] 'compare' (duration: 106.1367ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:29:59.544335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.005067ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T18:29:59.946709Z","caller":"traceutil/trace.go:171","msg":"trace[2130319463] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"121.51919ms","start":"2026-03-31T18:29:59.825171Z","end":"2026-03-31T18:29:59.94669Z","steps":["trace[2130319463] 'process raft request' (duration: 121.401707ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:00.956501Z","caller":"traceutil/trace.go:171","msg":"trace[402839341] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"103.722234ms","start":"2026-03-31T18:30:00.852751Z","end":"2026-03-31T18:30:00.956473Z","steps":["trace[402839341] 'process raft request' (duration: 103.53121ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:01.128799Z","caller":"traceutil/trace.go:171","msg":"trace[1915849155] linearizableReadLoop","detail":"{readStateIndex:1606; appliedIndex:1605; }","duration":"179.906785ms","start":"2026-03-31T18:30:00.948868Z","end":"2026-03-31T18:30:01.128775Z","steps":["trace[1915849155] 'read index received' (duration: 7.594338ms)","trace[1915849155] 'applied index is now lower than readState.Index' (duration: 172.311237ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:30:01.128959Z","caller":"traceutil/trace.go:171","msg":"trace[46326379] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"275.936999ms","start":"2026-03-31T18:30:00.853004Z","end":"2026-03-31T18:30:01.128941Z","steps":["trace[46326379] 'process raft request' (duration: 208.095644ms)","trace[46326379] 'compare' (duration: 67.584919ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:30:01.129188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.306034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:30:01.129251Z","caller":"traceutil/trace.go:171","msg":"trace[1098816518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1542; }","duration":"180.414717ms","start":"2026-03-31T18:30:00.948821Z","end":"2026-03-31T18:30:01.129235Z","steps":["trace[1098816518] 'agreement among raft nodes before linearized reading' (duration: 180.329405ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:02.083334Z","caller":"traceutil/trace.go:171","msg":"trace[1962279763] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"128.234888ms","start":"2026-03-31T18:30:01.955073Z","end":"2026-03-31T18:30:02.083308Z","steps":["trace[1962279763] 'process raft request' (duration: 127.92008ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:02.362122Z","caller":"traceutil/trace.go:171","msg":"trace[388051448] linearizableReadLoop","detail":"{readStateIndex:1611; appliedIndex:1609; }","duration":"313.082907ms","start":"2026-03-31T18:30:02.049016Z","end":"2026-03-31T18:30:02.362099Z","steps":["trace[388051448] 'read index received' (duration: 34.105137ms)","trace[388051448] 'applied index is now lower than readState.Index' (duration: 278.97679ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:30:02.362209Z","caller":"traceutil/trace.go:171","msg":"trace[182375330] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"318.458403ms","start":"2026-03-31T18:30:02.043716Z","end":"2026-03-31T18:30:02.362174Z","steps":["trace[182375330] 'process raft request' (duration: 318.222957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:02.362469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.428055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-03-31T18:30:02.362431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.676685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-31T18:30:02.362555Z","caller":"traceutil/trace.go:171","msg":"trace[1237021366] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1547; }","duration":"313.574398ms","start":"2026-03-31T18:30:02.048958Z","end":"2026-03-31T18:30:02.362532Z","steps":["trace[1237021366] 'agreement among raft nodes before linearized reading' (duration: 313.284541ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:02.362558Z","caller":"traceutil/trace.go:171","msg":"trace[2118710246] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1547; }","duration":"255.852489ms","start":"2026-03-31T18:30:02.106687Z","end":"2026-03-31T18:30:02.362539Z","steps":["trace[2118710246] 'agreement among raft nodes before linearized reading' (duration: 255.583723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:02.362572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:02.043683Z","time spent":"318.801651ms","remote":"127.0.0.1:44594","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-03-31T18:30:02.362598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:02.048931Z","time spent":"313.65714ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-31T18:30:03.237654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.39664ms","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-03-31T18:30:03.237843Z","caller":"traceutil/trace.go:171","msg":"trace[351842647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1552; }","duration":"104.633355ms","start":"2026-03-31T18:30:03.13319Z","end":"2026-03-31T18:30:03.237823Z","steps":["trace[351842647] 'range keys from in-memory index tree' (duration: 104.208395ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:03.596084Z","caller":"traceutil/trace.go:171","msg":"trace[201672309] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"353.803648ms","start":"2026-03-31T18:30:03.24226Z","end":"2026-03-31T18:30:03.596064Z","steps":["trace[201672309] 'process raft request' (duration: 353.684185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:03.596228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:03.242239Z","time spent":"353.91131ms","remote":"127.0.0.1:44594","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-03-31T18:30:03.896455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.944559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:30:03.896553Z","caller":"traceutil/trace.go:171","msg":"trace[136988849] linearizableReadLoop","detail":"{readStateIndex:1618; appliedIndex:1616; }","duration":"455.637797ms","start":"2026-03-31T18:30:03.440896Z","end":"2026-03-31T18:30:03.896534Z","steps":["trace[136988849] 'read index received' (duration: 155.132094ms)","trace[136988849] 'applied index is now lower than readState.Index' (duration: 300.504103ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:30:03.89661Z","caller":"traceutil/trace.go:171","msg":"trace[1985369780] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"653.254056ms","start":"2026-03-31T18:30:03.243327Z","end":"2026-03-31T18:30:03.896581Z","steps":["trace[1985369780] 'process raft request' (duration: 454.105302ms)","trace[1985369780] 'compare' (duration: 198.249243ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:30:03.896744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.843013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-31T18:30:03.896743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:03.24331Z","time spent":"653.365229ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T18:30:03.896786Z","caller":"traceutil/trace.go:171","msg":"trace[1903804189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1554; }","duration":"455.921234ms","start":"2026-03-31T18:30:03.440855Z","end":"2026-03-31T18:30:03.896777Z","steps":["trace[1903804189] 'agreement among raft nodes before linearized reading' (duration: 455.7637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:03.896825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:03.440833Z","time spent":"455.983496ms","remote":"127.0.0.1:44594","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-03-31T18:30:03.896995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.915415ms","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-03-31T18:30:03.897025Z","caller":"traceutil/trace.go:171","msg":"trace[1888130796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1554; }","duration":"170.981776ms","start":"2026-03-31T18:30:03.726036Z","end":"2026-03-31T18:30:03.897018Z","steps":["trace[1888130796] 'agreement among raft nodes before linearized reading' (duration: 170.900074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:03.897168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.147342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T18:30:03.89722Z","caller":"traceutil/trace.go:171","msg":"trace[1001169599] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1554; }","duration":"124.225794ms","start":"2026-03-31T18:30:03.77298Z","end":"2026-03-31T18:30:03.897206Z","steps":["trace[1001169599] 'agreement among raft nodes before linearized reading' (duration: 124.151422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:04.217074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.692024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:30:04.217255Z","caller":"traceutil/trace.go:171","msg":"trace[929551531] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"310.820834ms","start":"2026-03-31T18:30:03.906409Z","end":"2026-03-31T18:30:04.21723Z","steps":["trace[929551531] 'process raft request' (duration: 176.859873ms)","trace[929551531] 'compare' (duration: 133.519321ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:30:04.21733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:03.906372Z","time spent":"310.925327ms","remote":"127.0.0.1:44594","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-03-31T18:30:04.498183Z","caller":"traceutil/trace.go:171","msg":"trace[542138422] linearizableReadLoop","detail":"{readStateIndex:1620; appliedIndex:1618; }","duration":"577.61505ms","start":"2026-03-31T18:30:03.920542Z","end":"2026-03-31T18:30:04.498157Z","steps":["trace[542138422] 'read index received' (duration: 162.744544ms)","trace[542138422] 'applied index is now lower than readState.Index' (duration: 414.869446ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:30:04.498258Z","caller":"traceutil/trace.go:171","msg":"trace[1373625087] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"588.155945ms","start":"2026-03-31T18:30:03.910077Z","end":"2026-03-31T18:30:04.498233Z","steps":["trace[1373625087] 'process raft request' (duration: 496.827891ms)","trace[1373625087] 'compare' (duration: 91.1332ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:30:04.498427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.852615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"warn","ts":"2026-03-31T18:30:04.498395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:03.910057Z","time spent":"588.254498ms","remote":"127.0.0.1:44594","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-03-31T18:30:04.498506Z","caller":"traceutil/trace.go:171","msg":"trace[1763844446] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1556; }","duration":"577.966367ms","start":"2026-03-31T18:30:03.920517Z","end":"2026-03-31T18:30:04.498483Z","steps":["trace[1763844446] 'agreement among raft nodes before linearized reading' (duration: 577.737152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:04.498499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.00931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-31T18:30:04.498551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:03.920502Z","time spent":"578.04009ms","remote":"127.0.0.1:44594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-31T18:30:04.498569Z","caller":"traceutil/trace.go:171","msg":"trace[78085128] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:1556; }","duration":"442.087832ms","start":"2026-03-31T18:30:04.056464Z","end":"2026-03-31T18:30:04.498552Z","steps":["trace[78085128] 'agreement among raft nodes before linearized reading' (duration: 441.901958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:04.498617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:04.056412Z","time spent":"442.196654ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.124\" "} {"level":"warn","ts":"2026-03-31T18:30:04.498755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.048763ms","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-03-31T18:30:04.499335Z","caller":"traceutil/trace.go:171","msg":"trace[603263666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1556; }","duration":"410.667418ms","start":"2026-03-31T18:30:04.088649Z","end":"2026-03-31T18:30:04.499316Z","steps":["trace[603263666] 'agreement among raft nodes before linearized reading' (duration: 409.741876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:04.499592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T18:30:04.08863Z","time spent":"410.947344ms","remote":"127.0.0.1:44594","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-03-31T18:30:04.757268Z","caller":"traceutil/trace.go:171","msg":"trace[829630103] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"225.427538ms","start":"2026-03-31T18:30:04.5318Z","end":"2026-03-31T18:30:04.757228Z","steps":["trace[829630103] 'process raft request' (duration: 185.105896ms)","trace[829630103] 'compare' (duration: 40.049116ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:30:04.988589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.291875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:30:04.988708Z","caller":"traceutil/trace.go:171","msg":"trace[234086984] linearizableReadLoop","detail":"{readStateIndex:1625; appliedIndex:1624; }","duration":"226.014382ms","start":"2026-03-31T18:30:04.762679Z","end":"2026-03-31T18:30:04.988693Z","steps":["trace[234086984] 'read index received' (duration: 98.270306ms)","trace[234086984] 'applied index is now lower than readState.Index' (duration: 127.742746ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:30:04.988813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.141385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-31T18:30:04.988846Z","caller":"traceutil/trace.go:171","msg":"trace[233566770] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1560; }","duration":"226.272688ms","start":"2026-03-31T18:30:04.762564Z","end":"2026-03-31T18:30:04.988837Z","steps":["trace[233566770] 'agreement among raft nodes before linearized reading' (duration: 226.208287ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:04.988837Z","caller":"traceutil/trace.go:171","msg":"trace[129277431] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"227.456066ms","start":"2026-03-31T18:30:04.761304Z","end":"2026-03-31T18:30:04.98876Z","steps":["trace[129277431] 'process raft request' (duration: 99.72271ms)","trace[129277431] 'compare' (duration: 127.101771ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:30:04.989362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.56909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T18:30:04.989422Z","caller":"traceutil/trace.go:171","msg":"trace[60568690] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1560; }","duration":"162.680853ms","start":"2026-03-31T18:30:04.826728Z","end":"2026-03-31T18:30:04.989409Z","steps":["trace[60568690] 'agreement among raft nodes before linearized reading' (duration: 162.332045ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:37.347031Z","caller":"traceutil/trace.go:171","msg":"trace[1203178246] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"211.990859ms","start":"2026-03-31T18:30:37.135014Z","end":"2026-03-31T18:30:37.347005Z","steps":["trace[1203178246] 'process raft request' (duration: 211.495137ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:37.441328Z","caller":"traceutil/trace.go:171","msg":"trace[1697550442] linearizableReadLoop","detail":"{readStateIndex:1777; appliedIndex:1775; }","duration":"123.375455ms","start":"2026-03-31T18:30:37.317927Z","end":"2026-03-31T18:30:37.441303Z","steps":["trace[1697550442] 'read index received' (duration: 28.473665ms)","trace[1697550442] 'applied index is now lower than readState.Index' (duration: 94.90095ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T18:30:37.441456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.515989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T18:30:37.441506Z","caller":"traceutil/trace.go:171","msg":"trace[1474691513] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1706; }","duration":"123.642861ms","start":"2026-03-31T18:30:37.317846Z","end":"2026-03-31T18:30:37.441489Z","steps":["trace[1474691513] 'agreement among raft nodes before linearized reading' (duration: 123.547059ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:37.44153Z","caller":"traceutil/trace.go:171","msg":"trace[497342489] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"213.7767ms","start":"2026-03-31T18:30:37.227721Z","end":"2026-03-31T18:30:37.441497Z","steps":["trace[497342489] 'process raft request' (duration: 213.442502ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:44.527989Z","caller":"traceutil/trace.go:171","msg":"trace[385736367] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"120.073918ms","start":"2026-03-31T18:30:44.407888Z","end":"2026-03-31T18:30:44.527962Z","steps":["trace[385736367] 'process raft request' (duration: 119.938025ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:57.012427Z","caller":"traceutil/trace.go:171","msg":"trace[594250549] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"114.373395ms","start":"2026-03-31T18:30:56.898026Z","end":"2026-03-31T18:30:57.0124Z","steps":["trace[594250549] 'process raft request' (duration: 71.81623ms)","trace[594250549] 'compare' (duration: 42.195357ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:30:57.812732Z","caller":"traceutil/trace.go:171","msg":"trace[1717574384] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"277.561422ms","start":"2026-03-31T18:30:57.535145Z","end":"2026-03-31T18:30:57.812707Z","steps":["trace[1717574384] 'process raft request' (duration: 277.398298ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:57.820821Z","caller":"traceutil/trace.go:171","msg":"trace[254635387] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1871; }","duration":"255.450985ms","start":"2026-03-31T18:30:57.565353Z","end":"2026-03-31T18:30:57.820804Z","steps":["trace[254635387] 'read index received' (duration: 247.264253ms)","trace[254635387] 'applied index is now lower than readState.Index' (duration: 8.186082ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:30:57.820869Z","caller":"traceutil/trace.go:171","msg":"trace[1734476548] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"277.160403ms","start":"2026-03-31T18:30:57.543682Z","end":"2026-03-31T18:30:57.820842Z","steps":["trace[1734476548] 'process raft request' (duration: 277.03223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:57.820966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.570874ms","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-03-31T18:30:57.820992Z","caller":"traceutil/trace.go:171","msg":"trace[1015902435] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1798; }","duration":"248.631505ms","start":"2026-03-31T18:30:57.572353Z","end":"2026-03-31T18:30:57.820984Z","steps":["trace[1015902435] 'agreement among raft nodes before linearized reading' (duration: 248.534703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:57.82103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.66372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-31T18:30:57.821083Z","caller":"traceutil/trace.go:171","msg":"trace[386361977] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1798; }","duration":"255.761402ms","start":"2026-03-31T18:30:57.565309Z","end":"2026-03-31T18:30:57.82107Z","steps":["trace[386361977] 'agreement among raft nodes before linearized reading' (duration: 255.581078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:30:57.82114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.733044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-31T18:30:57.82124Z","caller":"traceutil/trace.go:171","msg":"trace[1354660259] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1798; }","duration":"244.850437ms","start":"2026-03-31T18:30:57.576354Z","end":"2026-03-31T18:30:57.821205Z","steps":["trace[1354660259] 'agreement among raft nodes before linearized reading' (duration: 244.617582ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:57.930593Z","caller":"traceutil/trace.go:171","msg":"trace[1165204975] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"105.59529ms","start":"2026-03-31T18:30:57.82497Z","end":"2026-03-31T18:30:57.930565Z","steps":["trace[1165204975] 'process raft request' (duration: 105.550669ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:30:57.93066Z","caller":"traceutil/trace.go:171","msg":"trace[1918804966] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"105.672232ms","start":"2026-03-31T18:30:57.824934Z","end":"2026-03-31T18:30:57.930606Z","steps":["trace[1918804966] 'process raft request' (duration: 34.6317ms)","trace[1918804966] 'compare' (duration: 70.668653ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:30:57.930721Z","caller":"traceutil/trace.go:171","msg":"trace[837903170] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"105.709263ms","start":"2026-03-31T18:30:57.824956Z","end":"2026-03-31T18:30:57.930666Z","steps":["trace[837903170] 'process raft request' (duration: 105.510388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:31:14.922242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.856085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T18:31:14.922363Z","caller":"traceutil/trace.go:171","msg":"trace[7002206] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"207.891573ms","start":"2026-03-31T18:31:14.714441Z","end":"2026-03-31T18:31:14.922333Z","steps":["trace[7002206] 'process raft request' (duration: 51.862963ms)","trace[7002206] 'compare' (duration: 155.737223ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:32:02.17257Z","caller":"traceutil/trace.go:171","msg":"trace[1761059796] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"184.263772ms","start":"2026-03-31T18:32:01.988274Z","end":"2026-03-31T18:32:02.172538Z","steps":["trace[1761059796] 'process raft request' (duration: 184.012346ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:32:02.173745Z","caller":"traceutil/trace.go:171","msg":"trace[1240577124] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"116.043765ms","start":"2026-03-31T18:32:02.05768Z","end":"2026-03-31T18:32:02.173724Z","steps":["trace[1240577124] 'process raft request' (duration: 115.938353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T18:32:02.38098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.811553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-03-31T18:32:02.38101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.504506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-03-31T18:32:02.381056Z","caller":"traceutil/trace.go:171","msg":"trace[1322330754] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2093; }","duration":"157.951246ms","start":"2026-03-31T18:32:02.223088Z","end":"2026-03-31T18:32:02.381039Z","steps":["trace[1322330754] 'range keys from in-memory index tree' (duration: 157.64911ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:32:02.381067Z","caller":"traceutil/trace.go:171","msg":"trace[561305706] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:2093; }","duration":"163.596708ms","start":"2026-03-31T18:32:02.21746Z","end":"2026-03-31T18:32:02.381056Z","steps":["trace[561305706] 'range keys from in-memory index tree' (duration: 163.388343ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:32:24.700019Z","caller":"traceutil/trace.go:171","msg":"trace[1050399590] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"153.901593ms","start":"2026-03-31T18:32:24.546081Z","end":"2026-03-31T18:32:24.699983Z","steps":["trace[1050399590] 'process raft request' (duration: 85.767327ms)","trace[1050399590] 'compare' (duration: 68.032103ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T18:32:56.189836Z","caller":"traceutil/trace.go:171","msg":"trace[470569470] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"163.496589ms","start":"2026-03-31T18:32:56.026314Z","end":"2026-03-31T18:32:56.18981Z","steps":["trace[470569470] 'process raft request' (duration: 163.348976ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T18:33:02.641821Z","caller":"traceutil/trace.go:171","msg":"trace[2010308604] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"242.767145ms","start":"2026-03-31T18:33:02.399025Z","end":"2026-03-31T18:33:02.641792Z","steps":["trace[2010308604] 'process raft request' (duration: 242.55958ms)"],"step_count":1}