{"level":"warn","ts":"2026-02-24T04:29:11.400811Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T04:29:11.400923Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-02-24T04:29:11.401004Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T04:29:11.401016Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-24T04:29:11.401035Z","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-02-24T04:29:11.401691Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-02-24T04:29:11.401761Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-02-24T04:29:11.406289Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.325485ms"} {"level":"info","ts":"2026-02-24T04:29:11.414312Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-02-24T04:29:11.414384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T04:29:11.414411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-02-24T04:29:11.414425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T04:29:11.414437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-02-24T04:29:11.414482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-02-24T04:29:11.428398Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T04:29:11.429975Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T04:29:11.434238Z","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-02-24T04:29:11.436961Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T04:29:11.437588Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T04:29:11.438905Z","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-02-24T04:29:11.439164Z","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-02-24T04:29:11.439187Z","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-02-24T04:29:11.439649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-02-24T04:29:11.442494Z","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-02-24T04:29:11.442674Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-24T04:29:11.442868Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-24T04:29:11.442899Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-24T04:29:11.443002Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T04:29:11.443096Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T04:29:12.015324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T04:29:12.015369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T04:29:12.015386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-02-24T04:29:12.015399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-02-24T04:29:12.015406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-24T04:29:12.015417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-02-24T04:29:12.015426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-24T04:29:12.016406Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:29:12.017261Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T04:29:12.0174Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:29:12.017442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:29:12.0175Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T04:29:12.017535Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T04:29:12.017592Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:29:12.017695Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:29:12.017724Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:29:12.02072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T04:29:12.020725Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"warn","ts":"2026-02-24T04:29:13.601915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.483385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-24T04:29:13.602226Z","caller":"traceutil/trace.go:171","msg":"trace[1227157068] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:19; }","duration":"155.557174ms","start":"2026-02-24T04:29:13.446653Z","end":"2026-02-24T04:29:13.60221Z","steps":["trace[1227157068] 'read index received' (duration: 36.241392ms)","trace[1227157068] 'applied index is now lower than readState.Index' (duration: 119.314602ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:13.602286Z","caller":"traceutil/trace.go:171","msg":"trace[808911472] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"195.405156ms","start":"2026-02-24T04:29:13.406838Z","end":"2026-02-24T04:29:13.602243Z","steps":["trace[808911472] 'process raft request' (duration: 195.185254ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:13.60246Z","caller":"traceutil/trace.go:171","msg":"trace[468731821] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"188.250787ms","start":"2026-02-24T04:29:13.414179Z","end":"2026-02-24T04:29:13.60243Z","steps":["trace[468731821] 'process raft request' (duration: 187.943355ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:13.602264Z","caller":"traceutil/trace.go:171","msg":"trace[1211538176] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"196.179322ms","start":"2026-02-24T04:29:13.405941Z","end":"2026-02-24T04:29:13.602121Z","steps":["trace[1211538176] 'process raft request' (duration: 76.948351ms)","trace[1211538176] 'compare' (duration: 118.356464ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:13.602592Z","caller":"traceutil/trace.go:171","msg":"trace[1564538477] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"176.753865ms","start":"2026-02-24T04:29:13.425698Z","end":"2026-02-24T04:29:13.602452Z","steps":["trace[1564538477] 'process raft request' (duration: 176.475313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:13.602666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.507483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-24T04:29:13.602735Z","caller":"traceutil/trace.go:171","msg":"trace[517629850] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:19; }","duration":"171.592313ms","start":"2026-02-24T04:29:13.431125Z","end":"2026-02-24T04:29:13.602717Z","steps":["trace[517629850] 'agreement among raft nodes before linearized reading' (duration: 171.420832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:13.604264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.8693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-zvhxc\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-24T04:29:13.604311Z","caller":"traceutil/trace.go:171","msg":"trace[396219915] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"153.095485ms","start":"2026-02-24T04:29:13.451206Z","end":"2026-02-24T04:29:13.604302Z","steps":["trace[396219915] 'process raft request' (duration: 152.406909ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:13.604318Z","caller":"traceutil/trace.go:171","msg":"trace[1075620049] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-zvhxc; range_end:; response_count:1; response_revision:24; }","duration":"153.940512ms","start":"2026-02-24T04:29:13.450361Z","end":"2026-02-24T04:29:13.604302Z","steps":["trace[1075620049] 'agreement among raft nodes before linearized reading' (duration: 153.830951ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:13.604399Z","caller":"traceutil/trace.go:171","msg":"trace[1996391504] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"151.182689ms","start":"2026-02-24T04:29:13.453187Z","end":"2026-02-24T04:29:13.60437Z","steps":["trace[1996391504] 'process raft request' (duration: 150.968008ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:13.604474Z","caller":"traceutil/trace.go:171","msg":"trace[1008948809] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"151.923735ms","start":"2026-02-24T04:29:13.452534Z","end":"2026-02-24T04:29:13.604457Z","steps":["trace[1008948809] 'process raft request' (duration: 151.597762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:13.604487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.969805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-24T04:29:13.604517Z","caller":"traceutil/trace.go:171","msg":"trace[1241051715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24; }","duration":"152.012435ms","start":"2026-02-24T04:29:13.452496Z","end":"2026-02-24T04:29:13.604508Z","steps":["trace[1241051715] 'agreement among raft nodes before linearized reading' (duration: 151.960185ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:13.604272Z","caller":"traceutil/trace.go:171","msg":"trace[381945179] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"152.080306ms","start":"2026-02-24T04:29:13.452175Z","end":"2026-02-24T04:29:13.604255Z","steps":["trace[381945179] 'process raft request' (duration: 151.926435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:13.60478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.76547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-24T04:29:13.604911Z","caller":"traceutil/trace.go:171","msg":"trace[1079648762] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:24; }","duration":"153.910681ms","start":"2026-02-24T04:29:13.450976Z","end":"2026-02-24T04:29:13.604887Z","steps":["trace[1079648762] 'agreement among raft nodes before linearized reading' (duration: 153.75562ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:13.604845Z","caller":"traceutil/trace.go:171","msg":"trace[1488862402] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"153.308837ms","start":"2026-02-24T04:29:13.451525Z","end":"2026-02-24T04:29:13.604834Z","steps":["trace[1488862402] 'process raft request' (duration: 152.533801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:14.003772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.452927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-24T04:29:14.003897Z","caller":"traceutil/trace.go:171","msg":"trace[1233811000] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"317.552721ms","start":"2026-02-24T04:29:13.686321Z","end":"2026-02-24T04:29:14.003874Z","steps":["trace[1233811000] 'process raft request' (duration: 153.899981ms)","trace[1233811000] 'compare' (duration: 163.244386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:14.003973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:13.686296Z","time spent":"317.647512ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:14.018371Z","caller":"traceutil/trace.go:171","msg":"trace[1669299959] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"329.764528ms","start":"2026-02-24T04:29:13.688577Z","end":"2026-02-24T04:29:14.018341Z","steps":["trace[1669299959] 'process raft request' (duration: 329.504146ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:14.018543Z","caller":"traceutil/trace.go:171","msg":"trace[1881338194] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"329.410735ms","start":"2026-02-24T04:29:13.689128Z","end":"2026-02-24T04:29:14.018539Z","steps":["trace[1881338194] 'process raft request' (duration: 329.123853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:14.018611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:13.68912Z","time spent":"329.441076ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:14.018688Z","caller":"traceutil/trace.go:171","msg":"trace[1245656848] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"330.239651ms","start":"2026-02-24T04:29:13.688428Z","end":"2026-02-24T04:29:14.018668Z","steps":["trace[1245656848] 'process raft request' (duration: 329.556276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:14.018762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:13.688414Z","time spent":"330.316542ms","remote":"127.0.0.1:52298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:14.018483Z","caller":"traceutil/trace.go:171","msg":"trace[1973893348] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"329.673637ms","start":"2026-02-24T04:29:13.688798Z","end":"2026-02-24T04:29:14.018472Z","steps":["trace[1973893348] 'process raft request' (duration: 329.331344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:14.018842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:13.688791Z","time spent":"330.021889ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:14.018507Z","caller":"traceutil/trace.go:171","msg":"trace[1609571937] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"329.623427ms","start":"2026-02-24T04:29:13.68888Z","end":"2026-02-24T04:29:14.018503Z","steps":["trace[1609571937] 'process raft request' (duration: 329.316305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:14.018912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:13.688875Z","time spent":"330.02266ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:14.018527Z","caller":"traceutil/trace.go:171","msg":"trace[191717327] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"329.590636ms","start":"2026-02-24T04:29:13.688931Z","end":"2026-02-24T04:29:14.018522Z","steps":["trace[191717327] 'process raft request' (duration: 329.297364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:14.018968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:13.688915Z","time spent":"330.04238ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T04:29:14.018713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:13.688571Z","time spent":"330.01352ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:14.555383Z","caller":"traceutil/trace.go:171","msg":"trace[1346339494] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"119.031641ms","start":"2026-02-24T04:29:14.436314Z","end":"2026-02-24T04:29:14.555346Z","steps":["trace[1346339494] 'process raft request' (duration: 90.236631ms)","trace[1346339494] 'compare' (duration: 28.672909ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:15.103335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.321901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-24T04:29:15.103411Z","caller":"traceutil/trace.go:171","msg":"trace[910561236] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"195.286385ms","start":"2026-02-24T04:29:14.908111Z","end":"2026-02-24T04:29:15.103397Z","steps":["trace[910561236] 'process raft request' (duration: 67.848083ms)","trace[910561236] 'compare' (duration: 127.20412ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:15.655146Z","caller":"traceutil/trace.go:171","msg":"trace[1894108799] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"158.92816ms","start":"2026-02-24T04:29:15.496185Z","end":"2026-02-24T04:29:15.655113Z","steps":["trace[1894108799] 'process raft request' (duration: 76.361916ms)","trace[1894108799] 'compare' (duration: 82.421603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:15.878168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.678376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:29:15.878286Z","caller":"traceutil/trace.go:171","msg":"trace[421491438] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"182.964481ms","start":"2026-02-24T04:29:15.695301Z","end":"2026-02-24T04:29:15.878266Z","steps":["trace[421491438] 'process raft request' (duration: 66.114605ms)","trace[421491438] 'compare' (duration: 116.526984ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:16.155701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.757633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:29:16.155955Z","caller":"traceutil/trace.go:171","msg":"trace[270662630] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"186.734232ms","start":"2026-02-24T04:29:15.969193Z","end":"2026-02-24T04:29:16.155927Z","steps":["trace[270662630] 'process raft request' (duration: 43.648906ms)","trace[270662630] 'compare' (duration: 142.558902ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:16.519325Z","caller":"traceutil/trace.go:171","msg":"trace[161719747] linearizableReadLoop","detail":"{readStateIndex:154; appliedIndex:153; }","duration":"132.003278ms","start":"2026-02-24T04:29:16.387283Z","end":"2026-02-24T04:29:16.519286Z","steps":["trace[161719747] 'read index received' (duration: 109.668293ms)","trace[161719747] 'applied index is now lower than readState.Index' (duration: 22.333705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:16.519709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.394652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:29:16.51983Z","caller":"traceutil/trace.go:171","msg":"trace[1195584520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:150; }","duration":"132.557704ms","start":"2026-02-24T04:29:16.387236Z","end":"2026-02-24T04:29:16.519793Z","steps":["trace[1195584520] 'agreement among raft nodes before linearized reading' (duration: 132.255852ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:16.519852Z","caller":"traceutil/trace.go:171","msg":"trace[1307251581] transaction","detail":"{read_only:false; response_revision:150; number_of_response:1; }","duration":"307.956373ms","start":"2026-02-24T04:29:16.211437Z","end":"2026-02-24T04:29:16.519393Z","steps":["trace[1307251581] 'process raft request' (duration: 285.528147ms)","trace[1307251581] 'compare' (duration: 22.159623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:16.520055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:16.211411Z","time spent":"308.556897ms","remote":"127.0.0.1:52204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":752,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:16.819825Z","caller":"traceutil/trace.go:171","msg":"trace[64982886] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"225.125293ms","start":"2026-02-24T04:29:16.59467Z","end":"2026-02-24T04:29:16.819795Z","steps":["trace[64982886] 'process raft request' (duration: 159.603026ms)","trace[64982886] 'compare' (duration: 65.351275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:17.106447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.904578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:29:17.106543Z","caller":"traceutil/trace.go:171","msg":"trace[9027452] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:0; response_revision:157; }","duration":"185.048929ms","start":"2026-02-24T04:29:16.92147Z","end":"2026-02-24T04:29:17.106519Z","steps":["trace[9027452] 'range keys from in-memory index tree' (duration: 184.834238ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:17.504097Z","caller":"traceutil/trace.go:171","msg":"trace[1850273165] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"193.958864ms","start":"2026-02-24T04:29:17.310108Z","end":"2026-02-24T04:29:17.504067Z","steps":["trace[1850273165] 'process raft request' (duration: 105.574464ms)","trace[1850273165] 'compare' (duration: 88.020298ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:17.504373Z","caller":"traceutil/trace.go:171","msg":"trace[1752546260] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"191.959468ms","start":"2026-02-24T04:29:17.312353Z","end":"2026-02-24T04:29:17.504313Z","steps":["trace[1752546260] 'process raft request' (duration: 191.595376ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:17.667696Z","caller":"traceutil/trace.go:171","msg":"trace[1213447077] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"160.723775ms","start":"2026-02-24T04:29:17.506942Z","end":"2026-02-24T04:29:17.667666Z","steps":["trace[1213447077] 'process raft request' (duration: 119.335772ms)","trace[1213447077] 'compare' (duration: 40.926049ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:17.668182Z","caller":"traceutil/trace.go:171","msg":"trace[578078501] linearizableReadLoop","detail":"{readStateIndex:183; appliedIndex:182; }","duration":"160.842677ms","start":"2026-02-24T04:29:17.50726Z","end":"2026-02-24T04:29:17.668103Z","steps":["trace[578078501] 'read index received' (duration: 119.096061ms)","trace[578078501] 'applied index is now lower than readState.Index' (duration: 41.740596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:17.668309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.029848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:29:17.668362Z","caller":"traceutil/trace.go:171","msg":"trace[1959529268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:179; }","duration":"161.123449ms","start":"2026-02-24T04:29:17.507226Z","end":"2026-02-24T04:29:17.668349Z","steps":["trace[1959529268] 'agreement among raft nodes before linearized reading' (duration: 161.006478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:17.668412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.933568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:29:17.668455Z","caller":"traceutil/trace.go:171","msg":"trace[462556762] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:0; response_revision:179; }","duration":"161.015398ms","start":"2026-02-24T04:29:17.507424Z","end":"2026-02-24T04:29:17.668439Z","steps":["trace[462556762] 'agreement among raft nodes before linearized reading' (duration: 160.914018ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:17.889409Z","caller":"traceutil/trace.go:171","msg":"trace[2016643931] linearizableReadLoop","detail":"{readStateIndex:187; appliedIndex:185; }","duration":"185.096086ms","start":"2026-02-24T04:29:17.70428Z","end":"2026-02-24T04:29:17.889376Z","steps":["trace[2016643931] 'read index received' (duration: 131.496906ms)","trace[2016643931] 'applied index is now lower than readState.Index' (duration: 53.59811ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:17.889776Z","caller":"traceutil/trace.go:171","msg":"trace[169453709] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"217.060325ms","start":"2026-02-24T04:29:17.67266Z","end":"2026-02-24T04:29:17.889721Z","steps":["trace[169453709] 'process raft request' (duration: 216.598332ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:17.889827Z","caller":"traceutil/trace.go:171","msg":"trace[2013311473] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"217.287577ms","start":"2026-02-24T04:29:17.67251Z","end":"2026-02-24T04:29:17.889798Z","steps":["trace[2013311473] 'process raft request' (duration: 163.261024ms)","trace[2013311473] 'compare' (duration: 53.230966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:17.890057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.73607ms","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-02-24T04:29:17.890127Z","caller":"traceutil/trace.go:171","msg":"trace[313823760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:182; }","duration":"185.869602ms","start":"2026-02-24T04:29:17.704236Z","end":"2026-02-24T04:29:17.890106Z","steps":["trace[313823760] 'agreement among raft nodes before linearized reading' (duration: 185.309278ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:18.172805Z","caller":"traceutil/trace.go:171","msg":"trace[2020068506] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"203.54313ms","start":"2026-02-24T04:29:17.969223Z","end":"2026-02-24T04:29:18.172766Z","steps":["trace[2020068506] 'process raft request' (duration: 124.487447ms)","trace[2020068506] 'compare' (duration: 78.895361ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:18.17292Z","caller":"traceutil/trace.go:171","msg":"trace[872014677] linearizableReadLoop","detail":"{readStateIndex:191; appliedIndex:190; }","duration":"192.704915ms","start":"2026-02-24T04:29:17.980132Z","end":"2026-02-24T04:29:18.172837Z","steps":["trace[872014677] 'read index received' (duration: 113.589362ms)","trace[872014677] 'applied index is now lower than readState.Index' (duration: 79.109803ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:18.172834Z","caller":"traceutil/trace.go:171","msg":"trace[360271952] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"191.477136ms","start":"2026-02-24T04:29:17.981341Z","end":"2026-02-24T04:29:18.172818Z","steps":["trace[360271952] 'process raft request' (duration: 191.429406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:18.1731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.941817ms","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-02-24T04:29:18.173161Z","caller":"traceutil/trace.go:171","msg":"trace[912330858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:187; }","duration":"193.058018ms","start":"2026-02-24T04:29:17.980086Z","end":"2026-02-24T04:29:18.173144Z","steps":["trace[912330858] 'agreement among raft nodes before linearized reading' (duration: 192.891237ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:18.782061Z","caller":"traceutil/trace.go:171","msg":"trace[1621469271] linearizableReadLoop","detail":"{readStateIndex:223; appliedIndex:222; }","duration":"102.511174ms","start":"2026-02-24T04:29:18.679521Z","end":"2026-02-24T04:29:18.782032Z","steps":["trace[1621469271] 'read index received' (duration: 14.965156ms)","trace[1621469271] 'applied index is now lower than readState.Index' (duration: 87.544338ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:18.782107Z","caller":"traceutil/trace.go:171","msg":"trace[1681298236] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"104.118836ms","start":"2026-02-24T04:29:18.677959Z","end":"2026-02-24T04:29:18.782078Z","steps":["trace[1681298236] 'process raft request' (duration: 16.586338ms)","trace[1681298236] 'compare' (duration: 87.306646ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:18.782228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.681025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:29:18.782264Z","caller":"traceutil/trace.go:171","msg":"trace[500728885] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:217; }","duration":"102.761375ms","start":"2026-02-24T04:29:18.679492Z","end":"2026-02-24T04:29:18.782253Z","steps":["trace[500728885] 'agreement among raft nodes before linearized reading' (duration: 102.638974ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:19.030682Z","caller":"traceutil/trace.go:171","msg":"trace[138022864] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"194.417714ms","start":"2026-02-24T04:29:18.836233Z","end":"2026-02-24T04:29:19.030651Z","steps":["trace[138022864] 'process raft request' (duration: 112.30713ms)","trace[138022864] 'compare' (duration: 81.967353ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:21.616426Z","caller":"traceutil/trace.go:171","msg":"trace[561425948] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"359.854679ms","start":"2026-02-24T04:29:21.256545Z","end":"2026-02-24T04:29:21.6164Z","steps":["trace[561425948] 'process raft request' (duration: 359.711558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:21.616576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:21.256511Z","time spent":"359.99899ms","remote":"127.0.0.1:52070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:21.626863Z","caller":"traceutil/trace.go:171","msg":"trace[720651343] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:333; }","duration":"265.341223ms","start":"2026-02-24T04:29:21.361501Z","end":"2026-02-24T04:29:21.626843Z","steps":["trace[720651343] 'read index received' (duration: 255.458378ms)","trace[720651343] 'applied index is now lower than readState.Index' (duration: 9.881955ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:21.626922Z","caller":"traceutil/trace.go:171","msg":"trace[1939263260] transaction","detail":"{read_only:false; number_of_response:0; response_revision:324; }","duration":"265.847267ms","start":"2026-02-24T04:29:21.361058Z","end":"2026-02-24T04:29:21.626906Z","steps":["trace[1939263260] 'process raft request' (duration: 265.681446ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:21.626992Z","caller":"traceutil/trace.go:171","msg":"trace[1410730507] transaction","detail":"{read_only:false; number_of_response:0; response_revision:324; }","duration":"265.587635ms","start":"2026-02-24T04:29:21.361367Z","end":"2026-02-24T04:29:21.626955Z","steps":["trace[1410730507] 'process raft request' (duration: 265.448194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:21.627064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.542505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"info","ts":"2026-02-24T04:29:21.627106Z","caller":"traceutil/trace.go:171","msg":"trace[2117260065] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:324; }","duration":"265.626975ms","start":"2026-02-24T04:29:21.361469Z","end":"2026-02-24T04:29:21.627096Z","steps":["trace[2117260065] 'agreement among raft nodes before linearized reading' (duration: 265.473534ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:22.002425Z","caller":"traceutil/trace.go:171","msg":"trace[969537507] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"102.474217ms","start":"2026-02-24T04:29:21.899924Z","end":"2026-02-24T04:29:22.002398Z","steps":["trace[969537507] 'read index received' (duration: 22.267908ms)","trace[969537507] 'applied index is now lower than readState.Index' (duration: 80.205529ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:22.002567Z","caller":"traceutil/trace.go:171","msg":"trace[350634223] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"146.214178ms","start":"2026-02-24T04:29:21.856332Z","end":"2026-02-24T04:29:22.002546Z","steps":["trace[350634223] 'process raft request' (duration: 65.853448ms)","trace[350634223] 'compare' (duration: 80.099409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:22.002799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.86628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:29:22.002853Z","caller":"traceutil/trace.go:171","msg":"trace[1807010061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:334; }","duration":"102.955011ms","start":"2026-02-24T04:29:21.899884Z","end":"2026-02-24T04:29:22.002839Z","steps":["trace[1807010061] 'agreement among raft nodes before linearized reading' (duration: 102.87357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:22.508641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365865722,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:29:22.552608Z","caller":"traceutil/trace.go:171","msg":"trace[1606008691] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:345; }","duration":"544.716204ms","start":"2026-02-24T04:29:22.007877Z","end":"2026-02-24T04:29:22.552593Z","steps":["trace[1606008691] 'read index received' (duration: 527.563924ms)","trace[1606008691] 'applied index is now lower than readState.Index' (duration: 17.15179ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:22.552654Z","caller":"traceutil/trace.go:171","msg":"trace[269150123] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"544.789604ms","start":"2026-02-24T04:29:22.007845Z","end":"2026-02-24T04:29:22.552634Z","steps":["trace[269150123] 'process raft request' (duration: 527.584594ms)","trace[269150123] 'compare' (duration: 16.954468ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:22.552671Z","caller":"traceutil/trace.go:171","msg":"trace[853531343] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"544.300271ms","start":"2026-02-24T04:29:22.008346Z","end":"2026-02-24T04:29:22.552646Z","steps":["trace[853531343] 'process raft request' (duration: 544.2243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:22.552734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.622214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T04:29:22.552755Z","caller":"traceutil/trace.go:171","msg":"trace[1052650304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:336; }","duration":"353.657045ms","start":"2026-02-24T04:29:22.199092Z","end":"2026-02-24T04:29:22.552749Z","steps":["trace[1052650304] 'agreement among raft nodes before linearized reading' (duration: 353.626754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:22.55277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:22.199083Z","time spent":"353.683745ms","remote":"127.0.0.1:52118","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-02-24T04:29:22.552703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.812204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:29:22.552806Z","caller":"traceutil/trace.go:171","msg":"trace[340519798] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:336; }","duration":"544.945895ms","start":"2026-02-24T04:29:22.007849Z","end":"2026-02-24T04:29:22.552795Z","steps":["trace[340519798] 'agreement among raft nodes before linearized reading' (duration: 544.816804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:22.552845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:22.007838Z","time spent":"544.994755ms","remote":"127.0.0.1:52070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-02-24T04:29:22.552934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:22.007828Z","time spent":"544.868014ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:29:22.553208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:22.008333Z","time spent":"544.393881ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:29:22.666986Z","caller":"traceutil/trace.go:171","msg":"trace[1425441241] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"107.536859ms","start":"2026-02-24T04:29:22.559428Z","end":"2026-02-24T04:29:22.666964Z","steps":["trace[1425441241] 'process raft request' (duration: 105.602915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:23.565347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:22.695089Z","time spent":"870.239255ms","remote":"127.0.0.1:52070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-02-24T04:29:24.627673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.977608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-24T04:29:24.627751Z","caller":"traceutil/trace.go:171","msg":"trace[706220889] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:388; }","duration":"340.060508ms","start":"2026-02-24T04:29:24.287657Z","end":"2026-02-24T04:29:24.627718Z","steps":["trace[706220889] 'range keys from in-memory index tree' (duration: 339.917468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:24.627778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:24.287649Z","time spent":"340.122879ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-24T04:29:24.627767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.2661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4015"} {"level":"info","ts":"2026-02-24T04:29:24.627832Z","caller":"traceutil/trace.go:171","msg":"trace[1415618026] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:388; }","duration":"340.366901ms","start":"2026-02-24T04:29:24.287452Z","end":"2026-02-24T04:29:24.627819Z","steps":["trace[1415618026] 'range keys from in-memory index tree' (duration: 340.068059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:24.627869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:24.287435Z","time spent":"340.426851ms","remote":"127.0.0.1:52040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-24T04:29:25.155045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365865821,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:29:25.369197Z","caller":"traceutil/trace.go:171","msg":"trace[732916379] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"738.867697ms","start":"2026-02-24T04:29:24.630305Z","end":"2026-02-24T04:29:25.369173Z","steps":["trace[732916379] 'process raft request' (duration: 738.728216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:25.369373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:24.630295Z","time spent":"738.978268ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:29:25.370164Z","caller":"traceutil/trace.go:171","msg":"trace[529803372] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:399; }","duration":"715.660744ms","start":"2026-02-24T04:29:24.654485Z","end":"2026-02-24T04:29:25.370145Z","steps":["trace[529803372] 'read index received' (duration: 714.197362ms)","trace[529803372] 'applied index is now lower than readState.Index' (duration: 1.462612ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:25.370365Z","caller":"traceutil/trace.go:171","msg":"trace[1332900175] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"739.765243ms","start":"2026-02-24T04:29:24.630584Z","end":"2026-02-24T04:29:25.370349Z","steps":["trace[1332900175] 'process raft request' (duration: 739.481271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:25.370483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:24.630566Z","time spent":"739.840384ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:29:25.370672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.181067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T04:29:25.370724Z","caller":"traceutil/trace.go:171","msg":"trace[2114924621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:390; }","duration":"716.251358ms","start":"2026-02-24T04:29:24.654461Z","end":"2026-02-24T04:29:25.370712Z","steps":["trace[2114924621] 'agreement among raft nodes before linearized reading' (duration: 716.173707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:25.37076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:24.654455Z","time spent":"716.297378ms","remote":"127.0.0.1:52118","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-02-24T04:29:25.370808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.157833ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:29:25.370759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.924717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T04:29:25.370869Z","caller":"traceutil/trace.go:171","msg":"trace[1778964922] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:390; }","duration":"550.228114ms","start":"2026-02-24T04:29:24.820635Z","end":"2026-02-24T04:29:25.370863Z","steps":["trace[1778964922] 'agreement among raft nodes before linearized reading' (duration: 550.140353ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:25.370911Z","caller":"traceutil/trace.go:171","msg":"trace[1206839874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:390; }","duration":"696.089478ms","start":"2026-02-24T04:29:24.6748Z","end":"2026-02-24T04:29:25.370889Z","steps":["trace[1206839874] 'agreement among raft nodes before linearized reading' (duration: 695.888887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:25.370982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:24.674792Z","time spent":"696.173819ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:29:33.386505Z","caller":"traceutil/trace.go:171","msg":"trace[2145336794] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"261.506008ms","start":"2026-02-24T04:29:33.124971Z","end":"2026-02-24T04:29:33.386477Z","steps":["trace[2145336794] 'process raft request' (duration: 261.379587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:44.141127Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365865994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:29:44.641237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365865994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:29:44.976131Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.38548383s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:29:44.9767Z","caller":"traceutil/trace.go:171","msg":"trace[1368090470] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"1.386130715s","start":"2026-02-24T04:29:43.590527Z","end":"2026-02-24T04:29:44.976657Z","steps":["trace[1368090470] 'process raft request' (duration: 1.385885953s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:44.976958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:43.590513Z","time spent":"1.386305016s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:29:45.104062Z","caller":"traceutil/trace.go:171","msg":"trace[1523858709] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"1.513358591s","start":"2026-02-24T04:29:43.590679Z","end":"2026-02-24T04:29:45.104037Z","steps":["trace[1523858709] 'process raft request' (duration: 1.51325336s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:45.104264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:43.590667Z","time spent":"1.513481313s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:29:45.104276Z","caller":"traceutil/trace.go:171","msg":"trace[823291836] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.513286842s","start":"2026-02-24T04:29:43.590954Z","end":"2026-02-24T04:29:45.104241Z","steps":["trace[823291836] 'process raft request' (duration: 1.513042598s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:45.104051Z","caller":"traceutil/trace.go:171","msg":"trace[1733343382] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"1.51339831s","start":"2026-02-24T04:29:43.590617Z","end":"2026-02-24T04:29:45.104015Z","steps":["trace[1733343382] 'process raft request' (duration: 1.477746331s)","trace[1733343382] 'compare' (duration: 35.408987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:45.104409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:43.590943Z","time spent":"1.513391593s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:29:45.104443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:43.590581Z","time spent":"1.513773565s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:29:45.217775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.654348ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T04:29:45.218056Z","caller":"traceutil/trace.go:171","msg":"trace[253284936] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:487; }","duration":"1.577962799s","start":"2026-02-24T04:29:43.64007Z","end":"2026-02-24T04:29:45.218032Z","steps":["trace[253284936] 'read index received' (duration: 1.336274378s)","trace[253284936] 'applied index is now lower than readState.Index' (duration: 241.687101ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:45.218155Z","caller":"traceutil/trace.go:171","msg":"trace[1127703621] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"735.710838ms","start":"2026-02-24T04:29:44.482434Z","end":"2026-02-24T04:29:45.218145Z","steps":["trace[1127703621] 'process raft request' (duration: 735.437535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:45.218271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:44.482417Z","time spent":"735.771949ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:29:45.313065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672972723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:29:45.313152Z","caller":"traceutil/trace.go:171","msg":"trace[562647435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"1.673104004s","start":"2026-02-24T04:29:43.640026Z","end":"2026-02-24T04:29:45.31313Z","steps":["trace[562647435] 'agreement among raft nodes before linearized reading' (duration: 1.57808611s)","trace[562647435] 'range keys from in-memory index tree' (duration: 94.900703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:45.313196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:43.640007Z","time spent":"1.673180324s","remote":"127.0.0.1:51838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:29:50.810124Z","caller":"traceutil/trace.go:171","msg":"trace[1569262278] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"100.439971ms","start":"2026-02-24T04:29:50.709661Z","end":"2026-02-24T04:29:50.810101Z","steps":["trace[1569262278] 'process raft request' (duration: 100.308795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.179206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365866153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:29:52.373472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.337448ms","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-02-24T04:29:52.373599Z","caller":"traceutil/trace.go:171","msg":"trace[2119639972] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:552; }","duration":"194.486635ms","start":"2026-02-24T04:29:52.179093Z","end":"2026-02-24T04:29:52.37358Z","steps":["trace[2119639972] 'range keys from in-memory index tree' (duration: 194.310467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.373608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243667561s","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-02-24T04:29:52.373739Z","caller":"traceutil/trace.go:171","msg":"trace[531214921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"1.243826779s","start":"2026-02-24T04:29:51.129886Z","end":"2026-02-24T04:29:52.373712Z","steps":["trace[531214921] 'range keys from in-memory index tree' (duration: 1.243501073s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.373621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242536169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T04:29:52.373798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.129875Z","time spent":"1.243907164s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T04:29:52.373823Z","caller":"traceutil/trace.go:171","msg":"trace[387101127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"1.2427825s","start":"2026-02-24T04:29:51.131016Z","end":"2026-02-24T04:29:52.373799Z","steps":["trace[387101127] 'range keys from in-memory index tree' (duration: 1.242317019s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.373857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.131Z","time spent":"1.242848824s","remote":"127.0.0.1:52118","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-02-24T04:29:52.373609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265038459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:29:52.373945Z","caller":"traceutil/trace.go:171","msg":"trace[1325180459] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"1.265406197s","start":"2026-02-24T04:29:51.108507Z","end":"2026-02-24T04:29:52.373913Z","steps":["trace[1325180459] 'range keys from in-memory index tree' (duration: 1.264898032s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.374009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.108489Z","time spent":"1.265499451s","remote":"127.0.0.1:51908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-24T04:29:52.373649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064730247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:29:52.374082Z","caller":"traceutil/trace.go:171","msg":"trace[1063154894] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"1.06521445s","start":"2026-02-24T04:29:51.308858Z","end":"2026-02-24T04:29:52.374072Z","steps":["trace[1063154894] 'range keys from in-memory index tree' (duration: 1.064652044s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.374103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.308836Z","time spent":"1.065260872s","remote":"127.0.0.1:51908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-24T04:29:52.373691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242635483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T04:29:52.374164Z","caller":"traceutil/trace.go:171","msg":"trace[1043344608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"1.243130897s","start":"2026-02-24T04:29:51.131029Z","end":"2026-02-24T04:29:52.37416Z","steps":["trace[1043344608] 'range keys from in-memory index tree' (duration: 1.242519417s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.374178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.131015Z","time spent":"1.243159747s","remote":"127.0.0.1:52118","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-02-24T04:29:52.437369Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.091877658s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:29:52.437733Z","caller":"traceutil/trace.go:171","msg":"trace[2095597273] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.092317318s","start":"2026-02-24T04:29:51.345395Z","end":"2026-02-24T04:29:52.437712Z","steps":["trace[2095597273] 'process raft request' (duration: 1.0921384s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.437855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.345372Z","time spent":"1.092415102s","remote":"127.0.0.1:52040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4367,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:29:52.595029Z","caller":"traceutil/trace.go:171","msg":"trace[616429012] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:572; }","duration":"956.52433ms","start":"2026-02-24T04:29:51.638482Z","end":"2026-02-24T04:29:52.595006Z","steps":["trace[616429012] 'read index received' (duration: 799.100569ms)","trace[616429012] 'applied index is now lower than readState.Index' (duration: 157.422741ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:52.595443Z","caller":"traceutil/trace.go:171","msg":"trace[579086575] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.084809263s","start":"2026-02-24T04:29:51.51062Z","end":"2026-02-24T04:29:52.595429Z","steps":["trace[579086575] 'process raft request' (duration: 1.084021447s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.595561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.510579Z","time spent":"1.084907218s","remote":"127.0.0.1:51908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:52.595722Z","caller":"traceutil/trace.go:171","msg":"trace[399812263] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"217.049866ms","start":"2026-02-24T04:29:52.378644Z","end":"2026-02-24T04:29:52.595694Z","steps":["trace[399812263] 'process raft request' (duration: 216.325823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.59591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.05539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-24T04:29:52.595951Z","caller":"traceutil/trace.go:171","msg":"trace[1738681581] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"687.093862ms","start":"2026-02-24T04:29:51.908847Z","end":"2026-02-24T04:29:52.595941Z","steps":["trace[1738681581] 'agreement among raft nodes before linearized reading' (duration: 686.972906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.595993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.908809Z","time spent":"687.174506ms","remote":"127.0.0.1:51908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-02-24T04:29:52.596021Z","caller":"traceutil/trace.go:171","msg":"trace[2019786063] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"880.682574ms","start":"2026-02-24T04:29:51.715327Z","end":"2026-02-24T04:29:52.596009Z","steps":["trace[2019786063] 'process raft request' (duration: 879.448717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.596122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.715308Z","time spent":"880.746337ms","remote":"127.0.0.1:51908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:29:52.596403Z","caller":"traceutil/trace.go:171","msg":"trace[1788887186] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"219.169202ms","start":"2026-02-24T04:29:52.377222Z","end":"2026-02-24T04:29:52.596391Z","steps":["trace[1788887186] 'process raft request' (duration: 217.628081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.596441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.286032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-24T04:29:52.596479Z","caller":"traceutil/trace.go:171","msg":"trace[1100440261] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"283.323034ms","start":"2026-02-24T04:29:52.313144Z","end":"2026-02-24T04:29:52.596467Z","steps":["trace[1100440261] 'agreement among raft nodes before linearized reading' (duration: 283.265221ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:52.596558Z","caller":"traceutil/trace.go:171","msg":"trace[1089782483] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"218.830097ms","start":"2026-02-24T04:29:52.377707Z","end":"2026-02-24T04:29:52.596537Z","steps":["trace[1089782483] 'process raft request' (duration: 217.224113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.595788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.308237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:29:52.596673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.13529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-24T04:29:52.596699Z","caller":"traceutil/trace.go:171","msg":"trace[435640702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"958.277171ms","start":"2026-02-24T04:29:51.63841Z","end":"2026-02-24T04:29:52.596687Z","steps":["trace[435640702] 'agreement among raft nodes before linearized reading' (duration: 957.296407ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:52.596704Z","caller":"traceutil/trace.go:171","msg":"trace[2039115363] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"488.215424ms","start":"2026-02-24T04:29:52.108479Z","end":"2026-02-24T04:29:52.596695Z","steps":["trace[2039115363] 'agreement among raft nodes before linearized reading' (duration: 488.161591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.596732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:52.108461Z","time spent":"488.264335ms","remote":"127.0.0.1:51908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-24T04:29:52.59674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.638385Z","time spent":"958.337844ms","remote":"127.0.0.1:51838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:29:52.596896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.242317ms","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-02-24T04:29:52.596949Z","caller":"traceutil/trace.go:171","msg":"trace[1006953042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:558; }","duration":"682.3126ms","start":"2026-02-24T04:29:51.914628Z","end":"2026-02-24T04:29:52.596941Z","steps":["trace[1006953042] 'agreement among raft nodes before linearized reading' (duration: 682.202925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:52.596983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:51.914624Z","time spent":"682.352782ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T04:29:58.988116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365866292,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:29:58.997655Z","caller":"traceutil/trace.go:171","msg":"trace[2095128923] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"510.476563ms","start":"2026-02-24T04:29:58.487154Z","end":"2026-02-24T04:29:58.997631Z","steps":["trace[2095128923] 'read index received' (duration: 510.225042ms)","trace[2095128923] 'applied index is now lower than readState.Index' (duration: 250.501µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:58.997768Z","caller":"traceutil/trace.go:171","msg":"trace[1732009389] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"528.125904ms","start":"2026-02-24T04:29:58.46961Z","end":"2026-02-24T04:29:58.997736Z","steps":["trace[1732009389] 'process raft request' (duration: 527.852253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:58.997926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:58.469579Z","time spent":"528.26749ms","remote":"127.0.0.1:52050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:29:58.99807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.87922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T04:29:58.998123Z","caller":"traceutil/trace.go:171","msg":"trace[802259097] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:611; }","duration":"511.018186ms","start":"2026-02-24T04:29:58.487094Z","end":"2026-02-24T04:29:58.998112Z","steps":["trace[802259097] 'agreement among raft nodes before linearized reading' (duration: 510.64678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:58.998165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:58.487078Z","time spent":"511.078568ms","remote":"127.0.0.1:51898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-02-24T04:29:58.998157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.06326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T04:29:58.998192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.896643ms","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-02-24T04:29:58.998274Z","caller":"traceutil/trace.go:171","msg":"trace[840417315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"366.214918ms","start":"2026-02-24T04:29:58.632029Z","end":"2026-02-24T04:29:58.998244Z","steps":["trace[840417315] 'agreement among raft nodes before linearized reading' (duration: 365.770509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:58.998158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.817577ms","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-02-24T04:29:58.998337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:58.632016Z","time spent":"366.307191ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T04:29:58.998341Z","caller":"traceutil/trace.go:171","msg":"trace[1958680180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"366.08182ms","start":"2026-02-24T04:29:58.63222Z","end":"2026-02-24T04:29:58.998302Z","steps":["trace[1958680180] 'agreement among raft nodes before linearized reading' (duration: 365.555668ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:29:58.998386Z","caller":"traceutil/trace.go:171","msg":"trace[703585941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:611; }","duration":"365.100199ms","start":"2026-02-24T04:29:58.633271Z","end":"2026-02-24T04:29:58.998372Z","steps":["trace[703585941] 'agreement among raft nodes before linearized reading' (duration: 364.572767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:58.998437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:58.633231Z","time spent":"365.192273ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T04:29:58.998439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:58.632201Z","time spent":"366.222296ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:29:58.999019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.469816ms","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-02-24T04:29:58.999103Z","caller":"traceutil/trace.go:171","msg":"trace[483984205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"381.59136ms","start":"2026-02-24T04:29:58.6175Z","end":"2026-02-24T04:29:58.999091Z","steps":["trace[483984205] 'agreement among raft nodes before linearized reading' (duration: 380.267364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:29:58.999184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:29:58.617473Z","time spent":"381.662993ms","remote":"127.0.0.1:52118","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-02-24T04:29:59.159176Z","caller":"traceutil/trace.go:171","msg":"trace[1016689279] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:634; }","duration":"122.584914ms","start":"2026-02-24T04:29:59.03656Z","end":"2026-02-24T04:29:59.159145Z","steps":["trace[1016689279] 'read index received' (duration: 48.559611ms)","trace[1016689279] 'applied index is now lower than readState.Index' (duration: 74.024133ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:29:59.159213Z","caller":"traceutil/trace.go:171","msg":"trace[914232009] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"148.337946ms","start":"2026-02-24T04:29:59.010838Z","end":"2026-02-24T04:29:59.159176Z","steps":["trace[914232009] 'process raft request' (duration: 110.081867ms)","trace[914232009] 'compare' (duration: 38.070551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:29:59.159403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.815013ms","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-02-24T04:29:59.159441Z","caller":"traceutil/trace.go:171","msg":"trace[481058406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"122.903357ms","start":"2026-02-24T04:29:59.036529Z","end":"2026-02-24T04:29:59.159432Z","steps":["trace[481058406] 'agreement among raft nodes before linearized reading' (duration: 122.719239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:01.713794Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365866313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:30:02.214949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365866313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:30:02.715612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365866313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:30:03.216412Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365866313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:30:03.638431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999951372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T04:30:03.638509Z","caller":"traceutil/trace.go:171","msg":"trace[12488887] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000084398s","start":"2026-02-24T04:30:01.638411Z","end":"2026-02-24T04:30:03.638495Z","steps":["trace[12488887] 'agreement among raft nodes before linearized reading' (duration: 1.999981834s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:03.638541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:01.638395Z","time spent":"2.000138769s","remote":"127.0.0.1:51838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:30:03.716565Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724507365866313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:30:03.861422Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.761027347s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:30:03.862212Z","caller":"traceutil/trace.go:171","msg":"trace[779766867] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"2.762045038s","start":"2026-02-24T04:30:01.100149Z","end":"2026-02-24T04:30:03.862194Z","steps":["trace[779766867] 'process raft request' (duration: 2.761456125s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:03.862318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:01.100137Z","time spent":"2.762125692s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:03.929274Z","caller":"traceutil/trace.go:171","msg":"trace[1261510997] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"2.825853095s","start":"2026-02-24T04:30:01.103398Z","end":"2026-02-24T04:30:03.929251Z","steps":["trace[1261510997] 'process raft request' (duration: 2.825781602s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:03.929293Z","caller":"traceutil/trace.go:171","msg":"trace[1179709075] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"2.827052835s","start":"2026-02-24T04:30:01.102202Z","end":"2026-02-24T04:30:03.929254Z","steps":["trace[1179709075] 'process raft request' (duration: 2.826653569s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:03.929315Z","caller":"traceutil/trace.go:171","msg":"trace[259533620] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"2.827025304s","start":"2026-02-24T04:30:01.102255Z","end":"2026-02-24T04:30:03.92928Z","steps":["trace[259533620] 'process raft request' (duration: 2.826887099s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:03.92944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:01.102191Z","time spent":"2.82717062s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:03.929447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:01.103373Z","time spent":"2.82597552s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:03.929479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:01.10225Z","time spent":"2.827138689s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:03.929592Z","caller":"traceutil/trace.go:171","msg":"trace[190314565] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"2.801951834s","start":"2026-02-24T04:30:01.127628Z","end":"2026-02-24T04:30:03.929579Z","steps":["trace[190314565] 'process raft request' (duration: 2.80158308s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:03.929641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:01.12761Z","time spent":"2.802004967s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:03.935247Z","caller":"traceutil/trace.go:171","msg":"trace[216061815] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:639; }","duration":"2.721907871s","start":"2026-02-24T04:30:01.213316Z","end":"2026-02-24T04:30:03.935224Z","steps":["trace[216061815] 'read index received' (duration: 2.648403772s)","trace[216061815] 'applied index is now lower than readState.Index' (duration: 73.502899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:03.935413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.258738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:30:03.935453Z","caller":"traceutil/trace.go:171","msg":"trace[1440297405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"296.335041ms","start":"2026-02-24T04:30:03.639107Z","end":"2026-02-24T04:30:03.935442Z","steps":["trace[1440297405] 'agreement among raft nodes before linearized reading' (duration: 296.256348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:03.935459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.72211596s","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-02-24T04:30:03.935579Z","caller":"traceutil/trace.go:171","msg":"trace[292520264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"2.722273157s","start":"2026-02-24T04:30:01.21328Z","end":"2026-02-24T04:30:03.935553Z","steps":["trace[292520264] 'agreement among raft nodes before linearized reading' (duration: 2.722052808s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:03.935669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:01.213266Z","time spent":"2.72238057s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:30:07.683229Z","caller":"traceutil/trace.go:171","msg":"trace[631687303] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"155.599795ms","start":"2026-02-24T04:30:07.527604Z","end":"2026-02-24T04:30:07.683204Z","steps":["trace[631687303] 'process raft request' (duration: 155.373616ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:14.110189Z","caller":"traceutil/trace.go:171","msg":"trace[250628140] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"141.113847ms","start":"2026-02-24T04:30:13.969055Z","end":"2026-02-24T04:30:14.110169Z","steps":["trace[250628140] 'read index received' (duration: 141.033054ms)","trace[250628140] 'applied index is now lower than readState.Index' (duration: 79.943µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:14.110265Z","caller":"traceutil/trace.go:171","msg":"trace[1838177284] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"196.503703ms","start":"2026-02-24T04:30:13.91372Z","end":"2026-02-24T04:30:14.110224Z","steps":["trace[1838177284] 'process raft request' (duration: 196.336477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:14.110396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.324275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:30:14.110471Z","caller":"traceutil/trace.go:171","msg":"trace[1896694860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"141.453329ms","start":"2026-02-24T04:30:13.969001Z","end":"2026-02-24T04:30:14.110454Z","steps":["trace[1896694860] 'agreement among raft nodes before linearized reading' (duration: 141.239861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:14.11471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.857625ms","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-02-24T04:30:14.114746Z","caller":"traceutil/trace.go:171","msg":"trace[1104725955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"140.920467ms","start":"2026-02-24T04:30:13.973818Z","end":"2026-02-24T04:30:14.114739Z","steps":["trace[1104725955] 'agreement among raft nodes before linearized reading' (duration: 140.811104ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:14.114693Z","caller":"traceutil/trace.go:171","msg":"trace[15530491] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"112.362525ms","start":"2026-02-24T04:30:14.002303Z","end":"2026-02-24T04:30:14.114666Z","steps":["trace[15530491] 'process raft request' (duration: 112.22927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:14.11488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.708181ms","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-02-24T04:30:14.114917Z","caller":"traceutil/trace.go:171","msg":"trace[233638933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"123.823355ms","start":"2026-02-24T04:30:13.991084Z","end":"2026-02-24T04:30:14.114908Z","steps":["trace[233638933] 'agreement among raft nodes before linearized reading' (duration: 123.642719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:14.114919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.86934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T04:30:14.115038Z","caller":"traceutil/trace.go:171","msg":"trace[279727716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"111.020226ms","start":"2026-02-24T04:30:14.003971Z","end":"2026-02-24T04:30:14.114992Z","steps":["trace[279727716] 'agreement among raft nodes before linearized reading' (duration: 110.744237ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:16.594103Z","caller":"traceutil/trace.go:171","msg":"trace[1004277406] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"161.744741ms","start":"2026-02-24T04:30:16.432302Z","end":"2026-02-24T04:30:16.594046Z","steps":["trace[1004277406] 'process raft request' (duration: 161.577155ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:17.393171Z","caller":"traceutil/trace.go:171","msg":"trace[220496343] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"266.412001ms","start":"2026-02-24T04:30:17.126725Z","end":"2026-02-24T04:30:17.393137Z","steps":["trace[220496343] 'process raft request' (duration: 266.294627ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:17.413166Z","caller":"traceutil/trace.go:171","msg":"trace[1133148150] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"284.206422ms","start":"2026-02-24T04:30:17.12894Z","end":"2026-02-24T04:30:17.413146Z","steps":["trace[1133148150] 'process raft request' (duration: 284.170541ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:17.413337Z","caller":"traceutil/trace.go:171","msg":"trace[1969717598] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"286.213992ms","start":"2026-02-24T04:30:17.127081Z","end":"2026-02-24T04:30:17.413295Z","steps":["trace[1969717598] 'process raft request' (duration: 285.938903ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:17.586584Z","caller":"traceutil/trace.go:171","msg":"trace[866679488] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"184.985528ms","start":"2026-02-24T04:30:17.401572Z","end":"2026-02-24T04:30:17.586557Z","steps":["trace[866679488] 'process raft request' (duration: 184.842212ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:18.349471Z","caller":"traceutil/trace.go:171","msg":"trace[719869001] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"130.344155ms","start":"2026-02-24T04:30:18.219104Z","end":"2026-02-24T04:30:18.349448Z","steps":["trace[719869001] 'process raft request' (duration: 127.062254ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:18.349725Z","caller":"traceutil/trace.go:171","msg":"trace[882660478] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"129.250537ms","start":"2026-02-24T04:30:18.220452Z","end":"2026-02-24T04:30:18.349703Z","steps":["trace[882660478] 'process raft request' (duration: 129.149894ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:18.349755Z","caller":"traceutil/trace.go:171","msg":"trace[922878956] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"130.251722ms","start":"2026-02-24T04:30:18.219481Z","end":"2026-02-24T04:30:18.349733Z","steps":["trace[922878956] 'process raft request' (duration: 129.926592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:18.57999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.776352ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-02-24T04:30:18.745651Z","caller":"traceutil/trace.go:171","msg":"trace[1661620986] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"117.680505ms","start":"2026-02-24T04:30:18.627897Z","end":"2026-02-24T04:30:18.745577Z","steps":["trace[1661620986] 'process raft request' (duration: 54.730119ms)","trace[1661620986] 'compare' (duration: 62.554083ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:18.745994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.198239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:30:18.746097Z","caller":"traceutil/trace.go:171","msg":"trace[351141046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"107.364465ms","start":"2026-02-24T04:30:18.638708Z","end":"2026-02-24T04:30:18.746072Z","steps":["trace[351141046] 'agreement among raft nodes before linearized reading' (duration: 107.154748ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:19.462423Z","caller":"traceutil/trace.go:171","msg":"trace[1521691490] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"195.400622ms","start":"2026-02-24T04:30:19.266999Z","end":"2026-02-24T04:30:19.4624Z","steps":["trace[1521691490] 'process raft request' (duration: 195.276008ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:19.608183Z","caller":"traceutil/trace.go:171","msg":"trace[1834677595] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"136.308318ms","start":"2026-02-24T04:30:19.471858Z","end":"2026-02-24T04:30:19.608166Z","steps":["trace[1834677595] 'process raft request' (duration: 55.118402ms)","trace[1834677595] 'compare' (duration: 81.080653ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:19.608225Z","caller":"traceutil/trace.go:171","msg":"trace[1954786219] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"101.039123ms","start":"2026-02-24T04:30:19.507101Z","end":"2026-02-24T04:30:19.60814Z","steps":["trace[1954786219] 'read index received' (duration: 19.818276ms)","trace[1954786219] 'applied index is now lower than readState.Index' (duration: 81.219837ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:19.608333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.223189ms","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-02-24T04:30:19.608362Z","caller":"traceutil/trace.go:171","msg":"trace[971691801] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:725; }","duration":"101.297822ms","start":"2026-02-24T04:30:19.507057Z","end":"2026-02-24T04:30:19.608354Z","steps":["trace[971691801] 'agreement among raft nodes before linearized reading' (duration: 101.202368ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:19.817325Z","caller":"traceutil/trace.go:171","msg":"trace[1792304651] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"168.442337ms","start":"2026-02-24T04:30:19.648848Z","end":"2026-02-24T04:30:19.81729Z","steps":["trace[1792304651] 'process raft request' (duration: 106.357682ms)","trace[1792304651] 'compare' (duration: 61.94327ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:20.116943Z","caller":"traceutil/trace.go:171","msg":"trace[1838373936] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"288.530339ms","start":"2026-02-24T04:30:19.828386Z","end":"2026-02-24T04:30:20.116916Z","steps":["trace[1838373936] 'process raft request' (duration: 203.991521ms)","trace[1838373936] 'compare' (duration: 84.428135ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:20.398159Z","caller":"traceutil/trace.go:171","msg":"trace[678343260] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:756; }","duration":"233.912921ms","start":"2026-02-24T04:30:20.164221Z","end":"2026-02-24T04:30:20.398134Z","steps":["trace[678343260] 'read index received' (duration: 217.591689ms)","trace[678343260] 'applied index is now lower than readState.Index' (duration: 16.319702ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:20.398177Z","caller":"traceutil/trace.go:171","msg":"trace[1604990309] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"568.325665ms","start":"2026-02-24T04:30:19.829834Z","end":"2026-02-24T04:30:20.398159Z","steps":["trace[1604990309] 'process raft request' (duration: 568.202041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:20.398444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.204451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-24T04:30:20.398474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:19.829828Z","time spent":"568.558053ms","remote":"127.0.0.1:52050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:20.398505Z","caller":"traceutil/trace.go:171","msg":"trace[2128928175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"234.319284ms","start":"2026-02-24T04:30:20.164173Z","end":"2026-02-24T04:30:20.398492Z","steps":["trace[2128928175] 'agreement among raft nodes before linearized reading' (duration: 234.152549ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:20.398207Z","caller":"traceutil/trace.go:171","msg":"trace[991901352] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"569.67486ms","start":"2026-02-24T04:30:19.828498Z","end":"2026-02-24T04:30:20.398172Z","steps":["trace[991901352] 'process raft request' (duration: 553.357237ms)","trace[991901352] 'compare' (duration: 16.064364ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:20.398636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.260256ms","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-02-24T04:30:20.398774Z","caller":"traceutil/trace.go:171","msg":"trace[1999832503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:730; }","duration":"167.397711ms","start":"2026-02-24T04:30:20.231358Z","end":"2026-02-24T04:30:20.398755Z","steps":["trace[1999832503] 'agreement among raft nodes before linearized reading' (duration: 167.084221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:20.398697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:19.828481Z","time spent":"570.183917ms","remote":"127.0.0.1:52026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:20.558799Z","caller":"traceutil/trace.go:171","msg":"trace[1048435593] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"155.198356ms","start":"2026-02-24T04:30:20.403573Z","end":"2026-02-24T04:30:20.558772Z","steps":["trace[1048435593] 'process raft request' (duration: 122.417918ms)","trace[1048435593] 'compare' (duration: 32.666945ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:20.561695Z","caller":"traceutil/trace.go:171","msg":"trace[843841301] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"154.6809ms","start":"2026-02-24T04:30:20.406999Z","end":"2026-02-24T04:30:20.56168Z","steps":["trace[843841301] 'process raft request' (duration: 154.637728ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:20.56215Z","caller":"traceutil/trace.go:171","msg":"trace[1428871513] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"158.414484ms","start":"2026-02-24T04:30:20.403723Z","end":"2026-02-24T04:30:20.562138Z","steps":["trace[1428871513] 'process raft request' (duration: 157.621837ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:20.562395Z","caller":"traceutil/trace.go:171","msg":"trace[1076769368] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"157.839785ms","start":"2026-02-24T04:30:20.404545Z","end":"2026-02-24T04:30:20.562385Z","steps":["trace[1076769368] 'process raft request' (duration: 156.883333ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:20.562624Z","caller":"traceutil/trace.go:171","msg":"trace[54929338] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"158.045092ms","start":"2026-02-24T04:30:20.404569Z","end":"2026-02-24T04:30:20.562614Z","steps":["trace[54929338] 'process raft request' (duration: 156.911903ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:20.562753Z","caller":"traceutil/trace.go:171","msg":"trace[1374806499] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"156.006574ms","start":"2026-02-24T04:30:20.406738Z","end":"2026-02-24T04:30:20.562745Z","steps":["trace[1374806499] 'process raft request' (duration: 154.780533ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:20.562891Z","caller":"traceutil/trace.go:171","msg":"trace[1755651751] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"156.089687ms","start":"2026-02-24T04:30:20.406793Z","end":"2026-02-24T04:30:20.562882Z","steps":["trace[1755651751] 'process raft request' (duration: 154.781573ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:20.563Z","caller":"traceutil/trace.go:171","msg":"trace[1368154191] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"156.077816ms","start":"2026-02-24T04:30:20.406914Z","end":"2026-02-24T04:30:20.562992Z","steps":["trace[1368154191] 'process raft request' (duration: 154.687389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:20.807859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.767254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:30:20.808024Z","caller":"traceutil/trace.go:171","msg":"trace[8997260] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"168.948213ms","start":"2026-02-24T04:30:20.639052Z","end":"2026-02-24T04:30:20.808Z","steps":["trace[8997260] 'read index received' (duration: 31.807666ms)","trace[8997260] 'applied index is now lower than readState.Index' (duration: 137.138817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:20.808103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.047666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:30:20.808149Z","caller":"traceutil/trace.go:171","msg":"trace[1212113087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"169.116338ms","start":"2026-02-24T04:30:20.639017Z","end":"2026-02-24T04:30:20.808133Z","steps":["trace[1212113087] 'agreement among raft nodes before linearized reading' (duration: 169.042866ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:20.808107Z","caller":"traceutil/trace.go:171","msg":"trace[14983539] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"236.796747ms","start":"2026-02-24T04:30:20.571257Z","end":"2026-02-24T04:30:20.808054Z","steps":["trace[14983539] 'process raft request' (duration: 99.666751ms)","trace[14983539] 'compare' (duration: 136.03955ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:21.064577Z","caller":"traceutil/trace.go:171","msg":"trace[607037884] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"251.04768ms","start":"2026-02-24T04:30:20.813463Z","end":"2026-02-24T04:30:21.06451Z","steps":["trace[607037884] 'process raft request' (duration: 250.974067ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:21.064954Z","caller":"traceutil/trace.go:171","msg":"trace[205114559] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"252.217138ms","start":"2026-02-24T04:30:20.812703Z","end":"2026-02-24T04:30:21.064921Z","steps":["trace[205114559] 'process raft request' (duration: 206.886618ms)","trace[205114559] 'compare' (duration: 44.7255ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:21.237644Z","caller":"traceutil/trace.go:171","msg":"trace[888871390] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"163.984411ms","start":"2026-02-24T04:30:21.073624Z","end":"2026-02-24T04:30:21.237608Z","steps":["trace[888871390] 'process raft request' (duration: 122.504658ms)","trace[888871390] 'compare' (duration: 41.333009ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:21.24863Z","caller":"traceutil/trace.go:171","msg":"trace[293982481] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"172.349066ms","start":"2026-02-24T04:30:21.076256Z","end":"2026-02-24T04:30:21.248605Z","steps":["trace[293982481] 'process raft request' (duration: 172.280044ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:21.248655Z","caller":"traceutil/trace.go:171","msg":"trace[495295326] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"174.733685ms","start":"2026-02-24T04:30:21.073907Z","end":"2026-02-24T04:30:21.248641Z","steps":["trace[495295326] 'process raft request' (duration: 174.498697ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:21.404258Z","caller":"traceutil/trace.go:171","msg":"trace[1365792040] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"104.387042ms","start":"2026-02-24T04:30:21.299844Z","end":"2026-02-24T04:30:21.404231Z","steps":["trace[1365792040] 'process raft request' (duration: 104.228126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:21.678472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.023946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:30:21.67892Z","caller":"traceutil/trace.go:171","msg":"trace[1271775329] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"176.201603ms","start":"2026-02-24T04:30:21.502664Z","end":"2026-02-24T04:30:21.678866Z","steps":["trace[1271775329] 'process raft request' (duration: 48.660591ms)","trace[1271775329] 'compare' (duration: 126.807428ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:21.859365Z","caller":"traceutil/trace.go:171","msg":"trace[1949573471] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"147.355314ms","start":"2026-02-24T04:30:21.711989Z","end":"2026-02-24T04:30:21.859345Z","steps":["trace[1949573471] 'process raft request' (duration: 76.116202ms)","trace[1949573471] 'compare' (duration: 71.134019ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:21.91184Z","caller":"traceutil/trace.go:171","msg":"trace[89544205] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"189.374685ms","start":"2026-02-24T04:30:21.722438Z","end":"2026-02-24T04:30:21.911813Z","steps":["trace[89544205] 'process raft request' (duration: 189.162758ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:22.142063Z","caller":"traceutil/trace.go:171","msg":"trace[841525331] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"191.25095ms","start":"2026-02-24T04:30:21.950778Z","end":"2026-02-24T04:30:22.142029Z","steps":["trace[841525331] 'process raft request' (duration: 191.073434ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:22.345793Z","caller":"traceutil/trace.go:171","msg":"trace[83512422] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:783; }","duration":"187.614803ms","start":"2026-02-24T04:30:22.158154Z","end":"2026-02-24T04:30:22.345769Z","steps":["trace[83512422] 'read index received' (duration: 51.812866ms)","trace[83512422] 'applied index is now lower than readState.Index' (duration: 135.800677ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:22.345879Z","caller":"traceutil/trace.go:171","msg":"trace[1813474188] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"192.333486ms","start":"2026-02-24T04:30:22.153511Z","end":"2026-02-24T04:30:22.345844Z","steps":["trace[1813474188] 'process raft request' (duration: 192.100588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:22.345925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.767258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:30:22.34596Z","caller":"traceutil/trace.go:171","msg":"trace[186186646] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:757; }","duration":"187.852491ms","start":"2026-02-24T04:30:22.158098Z","end":"2026-02-24T04:30:22.345951Z","steps":["trace[186186646] 'agreement among raft nodes before linearized reading' (duration: 187.761688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:22.607083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.489855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:30:22.607181Z","caller":"traceutil/trace.go:171","msg":"trace[1646237011] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"255.992987ms","start":"2026-02-24T04:30:22.35117Z","end":"2026-02-24T04:30:22.607163Z","steps":["trace[1646237011] 'process raft request' (duration: 129.347957ms)","trace[1646237011] 'compare' (duration: 126.257048ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:22.793592Z","caller":"traceutil/trace.go:171","msg":"trace[1795787041] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"230.416815ms","start":"2026-02-24T04:30:22.563145Z","end":"2026-02-24T04:30:22.793562Z","steps":["trace[1795787041] 'read index received' (duration: 180.260464ms)","trace[1795787041] 'applied index is now lower than readState.Index' (duration: 50.154421ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:22.793671Z","caller":"traceutil/trace.go:171","msg":"trace[421163279] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"440.839189ms","start":"2026-02-24T04:30:22.352788Z","end":"2026-02-24T04:30:22.793627Z","steps":["trace[421163279] 'process raft request' (duration: 440.688044ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:22.793728Z","caller":"traceutil/trace.go:171","msg":"trace[633817369] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"442.008158ms","start":"2026-02-24T04:30:22.351673Z","end":"2026-02-24T04:30:22.793681Z","steps":["trace[633817369] 'process raft request' (duration: 391.780034ms)","trace[633817369] 'compare' (duration: 49.876973ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:22.793808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:22.352781Z","time spent":"440.949052ms","remote":"127.0.0.1:52310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:22.793858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.524188ms","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-02-24T04:30:22.793893Z","caller":"traceutil/trace.go:171","msg":"trace[1414612991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"226.57845ms","start":"2026-02-24T04:30:22.567305Z","end":"2026-02-24T04:30:22.793884Z","steps":["trace[1414612991] 'agreement among raft nodes before linearized reading' (duration: 226.461377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:22.793888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.727825ms","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-02-24T04:30:22.793943Z","caller":"traceutil/trace.go:171","msg":"trace[1033257326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"230.835498ms","start":"2026-02-24T04:30:22.563092Z","end":"2026-02-24T04:30:22.793928Z","steps":["trace[1033257326] 'agreement among raft nodes before linearized reading' (duration: 230.641932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:22.793938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:22.351652Z","time spent":"442.179443ms","remote":"127.0.0.1:52122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:22.794021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.632077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:30:22.794043Z","caller":"traceutil/trace.go:171","msg":"trace[1228948968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"157.690999ms","start":"2026-02-24T04:30:22.636345Z","end":"2026-02-24T04:30:22.794036Z","steps":["trace[1228948968] 'agreement among raft nodes before linearized reading' (duration: 157.656548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:22.794197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.681818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T04:30:22.794221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.646407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T04:30:22.794241Z","caller":"traceutil/trace.go:171","msg":"trace[968470591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"224.74201ms","start":"2026-02-24T04:30:22.569487Z","end":"2026-02-24T04:30:22.794229Z","steps":["trace[968470591] 'agreement among raft nodes before linearized reading' (duration: 224.592906ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:22.794257Z","caller":"traceutil/trace.go:171","msg":"trace[74504511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"224.697639ms","start":"2026-02-24T04:30:22.569549Z","end":"2026-02-24T04:30:22.794247Z","steps":["trace[74504511] 'agreement among raft nodes before linearized reading' (duration: 224.594986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:22.796408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.937346ms","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-02-24T04:30:22.796483Z","caller":"traceutil/trace.go:171","msg":"trace[364265596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"229.04483ms","start":"2026-02-24T04:30:22.567421Z","end":"2026-02-24T04:30:22.796466Z","steps":["trace[364265596] 'agreement among raft nodes before linearized reading' (duration: 226.700354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:23.200406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.167044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:30:23.200644Z","caller":"traceutil/trace.go:171","msg":"trace[1882257880] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"400.448779ms","start":"2026-02-24T04:30:22.800169Z","end":"2026-02-24T04:30:23.200618Z","steps":["trace[1882257880] 'process raft request' (duration: 400.324554ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:23.200681Z","caller":"traceutil/trace.go:171","msg":"trace[453442849] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"397.550495ms","start":"2026-02-24T04:30:22.803098Z","end":"2026-02-24T04:30:23.200649Z","steps":["trace[453442849] 'process raft request' (duration: 397.512154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:23.200751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:22.80309Z","time spent":"397.629977ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:23.200792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:22.800158Z","time spent":"400.553953ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:23.200927Z","caller":"traceutil/trace.go:171","msg":"trace[561076041] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"401.301097ms","start":"2026-02-24T04:30:22.799614Z","end":"2026-02-24T04:30:23.200915Z","steps":["trace[561076041] 'process raft request' (duration: 286.542623ms)","trace[561076041] 'compare' (duration: 114.062341ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:23.200987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:22.799595Z","time spent":"401.36079ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:23.20099Z","caller":"traceutil/trace.go:171","msg":"trace[1483172817] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"400.336525ms","start":"2026-02-24T04:30:22.800642Z","end":"2026-02-24T04:30:23.200979Z","steps":["trace[1483172817] 'process raft request' (duration: 399.89841ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:23.201154Z","caller":"traceutil/trace.go:171","msg":"trace[63312120] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"398.479786ms","start":"2026-02-24T04:30:22.802666Z","end":"2026-02-24T04:30:23.201145Z","steps":["trace[63312120] 'process raft request' (duration: 397.904757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:23.20117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:22.800633Z","time spent":"400.506891ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:23.201221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:22.802619Z","time spent":"398.570079ms","remote":"127.0.0.1:52300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:23.21829Z","caller":"traceutil/trace.go:171","msg":"trace[1556859182] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"247.718456ms","start":"2026-02-24T04:30:22.970556Z","end":"2026-02-24T04:30:23.218274Z","steps":["trace[1556859182] 'process raft request' (duration: 247.590612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:23.94622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.343116ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T04:30:24.517737Z","caller":"traceutil/trace.go:171","msg":"trace[544400560] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"106.644107ms","start":"2026-02-24T04:30:24.411071Z","end":"2026-02-24T04:30:24.517716Z","steps":["trace[544400560] 'process raft request' (duration: 106.458681ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:25.033839Z","caller":"traceutil/trace.go:171","msg":"trace[1187675924] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"193.296556ms","start":"2026-02-24T04:30:24.840518Z","end":"2026-02-24T04:30:25.033815Z","steps":["trace[1187675924] 'process raft request' (duration: 193.135151ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:25.445102Z","caller":"traceutil/trace.go:171","msg":"trace[1794487417] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"156.37666ms","start":"2026-02-24T04:30:25.288702Z","end":"2026-02-24T04:30:25.445079Z","steps":["trace[1794487417] 'process raft request' (duration: 155.992498ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:25.451117Z","caller":"traceutil/trace.go:171","msg":"trace[850124708] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"161.622385ms","start":"2026-02-24T04:30:25.289471Z","end":"2026-02-24T04:30:25.451094Z","steps":["trace[850124708] 'process raft request' (duration: 161.578484ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:25.451501Z","caller":"traceutil/trace.go:171","msg":"trace[432080067] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"162.524083ms","start":"2026-02-24T04:30:25.28896Z","end":"2026-02-24T04:30:25.451484Z","steps":["trace[432080067] 'process raft request' (duration: 161.908754ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:25.45158Z","caller":"traceutil/trace.go:171","msg":"trace[1276216517] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"162.297296ms","start":"2026-02-24T04:30:25.289143Z","end":"2026-02-24T04:30:25.45144Z","steps":["trace[1276216517] 'process raft request' (duration: 161.867623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:25.713099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.079193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:30:25.713169Z","caller":"traceutil/trace.go:171","msg":"trace[1586458271] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:773; }","duration":"178.215007ms","start":"2026-02-24T04:30:25.534939Z","end":"2026-02-24T04:30:25.713154Z","steps":["trace[1586458271] 'count revisions from in-memory index tree' (duration: 177.98884ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:26.739601Z","caller":"traceutil/trace.go:171","msg":"trace[1668239487] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"100.670811ms","start":"2026-02-24T04:30:26.638907Z","end":"2026-02-24T04:30:26.739578Z","steps":["trace[1668239487] 'read index received' (duration: 100.454964ms)","trace[1668239487] 'applied index is now lower than readState.Index' (duration: 215.157µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:26.739706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.781604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:30:26.739761Z","caller":"traceutil/trace.go:171","msg":"trace[719483328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"100.827205ms","start":"2026-02-24T04:30:26.638897Z","end":"2026-02-24T04:30:26.739724Z","steps":["trace[719483328] 'agreement among raft nodes before linearized reading' (duration: 100.762283ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:26.739762Z","caller":"traceutil/trace.go:171","msg":"trace[173942665] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"141.284638ms","start":"2026-02-24T04:30:26.598411Z","end":"2026-02-24T04:30:26.739695Z","steps":["trace[173942665] 'process raft request' (duration: 141.00501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:27.628691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.345026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:30:27.628837Z","caller":"traceutil/trace.go:171","msg":"trace[652013954] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"589.883895ms","start":"2026-02-24T04:30:27.038934Z","end":"2026-02-24T04:30:27.628818Z","steps":["trace[652013954] 'read index received' (duration: 321.26299ms)","trace[652013954] 'applied index is now lower than readState.Index' (duration: 268.618625ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:27.628869Z","caller":"traceutil/trace.go:171","msg":"trace[356384274] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"737.714785ms","start":"2026-02-24T04:30:26.891126Z","end":"2026-02-24T04:30:27.628841Z","steps":["trace[356384274] 'process raft request' (duration: 469.137612ms)","trace[356384274] 'compare' (duration: 267.977295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:27.628998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:26.891103Z","time spent":"737.831769ms","remote":"127.0.0.1:51934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T04:30:27.629135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.196074ms","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-02-24T04:30:27.629182Z","caller":"traceutil/trace.go:171","msg":"trace[944623327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"590.285156ms","start":"2026-02-24T04:30:27.038883Z","end":"2026-02-24T04:30:27.629168Z","steps":["trace[944623327] 'agreement among raft nodes before linearized reading' (duration: 590.041079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:27.629198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.07899ms","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-02-24T04:30:27.629227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:27.038866Z","time spent":"590.349589ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T04:30:27.629279Z","caller":"traceutil/trace.go:171","msg":"trace[1860166042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"180.197184ms","start":"2026-02-24T04:30:27.449062Z","end":"2026-02-24T04:30:27.629259Z","steps":["trace[1860166042] 'agreement among raft nodes before linearized reading' (duration: 180.009378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:27.629333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.944331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T04:30:27.629228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.879199ms","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-02-24T04:30:27.629382Z","caller":"traceutil/trace.go:171","msg":"trace[1352417460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"174.009062ms","start":"2026-02-24T04:30:27.455357Z","end":"2026-02-24T04:30:27.629366Z","steps":["trace[1352417460] 'agreement among raft nodes before linearized reading' (duration: 173.904089ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:27.62945Z","caller":"traceutil/trace.go:171","msg":"trace[1882283833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"174.080235ms","start":"2026-02-24T04:30:27.455312Z","end":"2026-02-24T04:30:27.629392Z","steps":["trace[1882283833] 'agreement among raft nodes before linearized reading' (duration: 173.782436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:27.629456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.335773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T04:30:27.629501Z","caller":"traceutil/trace.go:171","msg":"trace[2136713057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"174.428695ms","start":"2026-02-24T04:30:27.455062Z","end":"2026-02-24T04:30:27.629491Z","steps":["trace[2136713057] 'agreement among raft nodes before linearized reading' (duration: 174.24862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:28.094999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.581123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:30:28.095144Z","caller":"traceutil/trace.go:171","msg":"trace[1999474424] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"460.510691ms","start":"2026-02-24T04:30:27.634616Z","end":"2026-02-24T04:30:28.095126Z","steps":["trace[1999474424] 'process raft request' (duration: 283.729242ms)","trace[1999474424] 'compare' (duration: 176.351956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:28.095199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:27.634597Z","time spent":"460.576223ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:28.09522Z","caller":"traceutil/trace.go:171","msg":"trace[1143263775] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"458.792847ms","start":"2026-02-24T04:30:27.63642Z","end":"2026-02-24T04:30:28.095213Z","steps":["trace[1143263775] 'process raft request' (duration: 458.766797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:28.095259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:27.636406Z","time spent":"458.831858ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:28.09528Z","caller":"traceutil/trace.go:171","msg":"trace[1884859217] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"460.658025ms","start":"2026-02-24T04:30:27.634616Z","end":"2026-02-24T04:30:28.095274Z","steps":["trace[1884859217] 'process raft request' (duration: 460.458879ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:28.09531Z","caller":"traceutil/trace.go:171","msg":"trace[1022014598] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"460.624504ms","start":"2026-02-24T04:30:27.634679Z","end":"2026-02-24T04:30:28.095304Z","steps":["trace[1022014598] 'process raft request' (duration: 460.455119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:28.095314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:27.634601Z","time spent":"460.694377ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:28.095344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:27.634671Z","time spent":"460.653965ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:28.095389Z","caller":"traceutil/trace.go:171","msg":"trace[1134301084] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"460.668775ms","start":"2026-02-24T04:30:27.634714Z","end":"2026-02-24T04:30:28.095383Z","steps":["trace[1134301084] 'process raft request' (duration: 460.446059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:28.095424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:27.634693Z","time spent":"460.711507ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:28.909218Z","caller":"traceutil/trace.go:171","msg":"trace[1644289243] transaction","detail":"{read_only:false; number_of_response:1; response_revision:786; }","duration":"117.192281ms","start":"2026-02-24T04:30:28.791999Z","end":"2026-02-24T04:30:28.909191Z","steps":["trace[1644289243] 'process raft request' (duration: 54.754303ms)","trace[1644289243] 'compare' (duration: 62.293464ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:42.385071Z","caller":"traceutil/trace.go:171","msg":"trace[2120853920] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:859; }","duration":"127.986791ms","start":"2026-02-24T04:30:42.257062Z","end":"2026-02-24T04:30:42.385049Z","steps":["trace[2120853920] 'read index received' (duration: 113.154245ms)","trace[2120853920] 'applied index is now lower than readState.Index' (duration: 14.831485ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:42.38519Z","caller":"traceutil/trace.go:171","msg":"trace[724736388] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"129.473799ms","start":"2026-02-24T04:30:42.255705Z","end":"2026-02-24T04:30:42.385179Z","steps":["trace[724736388] 'process raft request' (duration: 114.494899ms)","trace[724736388] 'compare' (duration: 14.650281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:42.385417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.050876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-24T04:30:42.385428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.34787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T04:30:42.385465Z","caller":"traceutil/trace.go:171","msg":"trace[1063279470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:828; }","duration":"109.131717ms","start":"2026-02-24T04:30:42.276321Z","end":"2026-02-24T04:30:42.385453Z","steps":["trace[1063279470] 'agreement among raft nodes before linearized reading' (duration: 108.928752ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:42.3855Z","caller":"traceutil/trace.go:171","msg":"trace[1931536302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"128.449922ms","start":"2026-02-24T04:30:42.257034Z","end":"2026-02-24T04:30:42.385484Z","steps":["trace[1931536302] 'agreement among raft nodes before linearized reading' (duration: 128.173185ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:42.385663Z","caller":"traceutil/trace.go:171","msg":"trace[264410299] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"129.535291ms","start":"2026-02-24T04:30:42.256116Z","end":"2026-02-24T04:30:42.385651Z","steps":["trace[264410299] 'process raft request' (duration: 128.860794ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:42.529795Z","caller":"traceutil/trace.go:171","msg":"trace[1626410510] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"140.39605ms","start":"2026-02-24T04:30:42.389365Z","end":"2026-02-24T04:30:42.529761Z","steps":["trace[1626410510] 'process raft request' (duration: 96.163873ms)","trace[1626410510] 'compare' (duration: 44.049362ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:42.534864Z","caller":"traceutil/trace.go:171","msg":"trace[1842440939] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"145.387464ms","start":"2026-02-24T04:30:42.389453Z","end":"2026-02-24T04:30:42.53484Z","steps":["trace[1842440939] 'process raft request' (duration: 145.26242ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:57.215711Z","caller":"traceutil/trace.go:171","msg":"trace[1421578616] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"117.522308ms","start":"2026-02-24T04:30:57.098146Z","end":"2026-02-24T04:30:57.215669Z","steps":["trace[1421578616] 'process raft request' (duration: 117.272353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:59.531776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.049163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:30:59.531894Z","caller":"traceutil/trace.go:171","msg":"trace[1091137327] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"546.482153ms","start":"2026-02-24T04:30:58.985389Z","end":"2026-02-24T04:30:59.531871Z","steps":["trace[1091137327] 'process raft request' (duration: 342.205204ms)","trace[1091137327] 'compare' (duration: 203.931761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:59.53197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:58.98536Z","time spent":"546.569245ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:59.771272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.521346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:30:59.77134Z","caller":"traceutil/trace.go:171","msg":"trace[621821941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"132.644959ms","start":"2026-02-24T04:30:59.638682Z","end":"2026-02-24T04:30:59.771327Z","steps":["trace[621821941] 'range keys from in-memory index tree' (duration: 132.456994ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:02.60837Z","caller":"traceutil/trace.go:171","msg":"trace[1731147996] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"108.952185ms","start":"2026-02-24T04:31:02.499393Z","end":"2026-02-24T04:31:02.608345Z","steps":["trace[1731147996] 'process raft request' (duration: 108.800101ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:05.03681Z","caller":"traceutil/trace.go:171","msg":"trace[515750261] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"113.626693ms","start":"2026-02-24T04:31:04.923162Z","end":"2026-02-24T04:31:05.036789Z","steps":["trace[515750261] 'read index received' (duration: 113.434649ms)","trace[515750261] 'applied index is now lower than readState.Index' (duration: 190.854µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:05.036914Z","caller":"traceutil/trace.go:171","msg":"trace[193759853] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"185.248577ms","start":"2026-02-24T04:31:04.851619Z","end":"2026-02-24T04:31:05.036868Z","steps":["trace[193759853] 'process raft request' (duration: 184.92428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:05.037017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.838777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T04:31:05.037111Z","caller":"traceutil/trace.go:171","msg":"trace[1151929691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"113.980921ms","start":"2026-02-24T04:31:04.923119Z","end":"2026-02-24T04:31:05.0371Z","steps":["trace[1151929691] 'agreement among raft nodes before linearized reading' (duration: 113.762876ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:11.260621Z","caller":"traceutil/trace.go:171","msg":"trace[36224692] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"170.026898ms","start":"2026-02-24T04:31:11.090571Z","end":"2026-02-24T04:31:11.260598Z","steps":["trace[36224692] 'read index received' (duration: 169.874575ms)","trace[36224692] 'applied index is now lower than readState.Index' (duration: 151.343µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:11.260736Z","caller":"traceutil/trace.go:171","msg":"trace[730173527] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"184.008209ms","start":"2026-02-24T04:31:11.076698Z","end":"2026-02-24T04:31:11.260706Z","steps":["trace[730173527] 'process raft request' (duration: 183.740424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:11.26086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.271863ms","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-02-24T04:31:11.260898Z","caller":"traceutil/trace.go:171","msg":"trace[55431634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:907; }","duration":"170.364795ms","start":"2026-02-24T04:31:11.090521Z","end":"2026-02-24T04:31:11.260886Z","steps":["trace[55431634] 'agreement among raft nodes before linearized reading' (duration: 170.216632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:11.260906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.237477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T04:31:11.260938Z","caller":"traceutil/trace.go:171","msg":"trace[691613702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"164.32817ms","start":"2026-02-24T04:31:11.096601Z","end":"2026-02-24T04:31:11.260929Z","steps":["trace[691613702] 'agreement among raft nodes before linearized reading' (duration: 164.186697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:11.260958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.596054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:31:11.261039Z","caller":"traceutil/trace.go:171","msg":"trace[1790732124] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:907; }","duration":"102.741358ms","start":"2026-02-24T04:31:11.158278Z","end":"2026-02-24T04:31:11.26102Z","steps":["trace[1790732124] 'agreement among raft nodes before linearized reading' (duration: 102.571944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:11.504722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.439934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:31:11.50515Z","caller":"traceutil/trace.go:171","msg":"trace[1559509250] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"240.758801ms","start":"2026-02-24T04:31:11.264371Z","end":"2026-02-24T04:31:11.50513Z","steps":["trace[1559509250] 'process raft request' (duration: 240.69845ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:11.505391Z","caller":"traceutil/trace.go:171","msg":"trace[1658777041] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"241.249921ms","start":"2026-02-24T04:31:11.26387Z","end":"2026-02-24T04:31:11.50512Z","steps":["trace[1658777041] 'process raft request' (duration: 109.316415ms)","trace[1658777041] 'compare' (duration: 130.896303ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:17.620001Z","caller":"traceutil/trace.go:171","msg":"trace[1839536661] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"124.359304ms","start":"2026-02-24T04:31:17.495595Z","end":"2026-02-24T04:31:17.619954Z","steps":["trace[1839536661] 'process raft request' (duration: 124.100229ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:17.822622Z","caller":"traceutil/trace.go:171","msg":"trace[496780842] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:966; }","duration":"183.781158ms","start":"2026-02-24T04:31:17.638815Z","end":"2026-02-24T04:31:17.822596Z","steps":["trace[496780842] 'read index received' (duration: 36.195871ms)","trace[496780842] 'applied index is now lower than readState.Index' (duration: 147.583987ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:17.82276Z","caller":"traceutil/trace.go:171","msg":"trace[1926090024] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"194.786007ms","start":"2026-02-24T04:31:17.627961Z","end":"2026-02-24T04:31:17.822747Z","steps":["trace[1926090024] 'process raft request' (duration: 194.510432ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:17.822904Z","caller":"traceutil/trace.go:171","msg":"trace[1969199662] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"194.999831ms","start":"2026-02-24T04:31:17.627896Z","end":"2026-02-24T04:31:17.822896Z","steps":["trace[1969199662] 'process raft request' (duration: 194.42292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:17.822879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.042243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:17.82296Z","caller":"traceutil/trace.go:171","msg":"trace[1692001810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"184.156816ms","start":"2026-02-24T04:31:17.638786Z","end":"2026-02-24T04:31:17.822942Z","steps":["trace[1692001810] 'agreement among raft nodes before linearized reading' (duration: 184.025584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:17.851672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.773617ms","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-02-24T04:31:17.851775Z","caller":"traceutil/trace.go:171","msg":"trace[211485653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"180.917431ms","start":"2026-02-24T04:31:17.670842Z","end":"2026-02-24T04:31:17.851759Z","steps":["trace[211485653] 'agreement among raft nodes before linearized reading' (duration: 180.726087ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:26.500691Z","caller":"traceutil/trace.go:171","msg":"trace[1532545935] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"370.580019ms","start":"2026-02-24T04:31:26.130095Z","end":"2026-02-24T04:31:26.500675Z","steps":["trace[1532545935] 'process raft request' (duration: 370.479977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:26.500816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:26.130072Z","time spent":"370.67927ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:31:40.193867Z","caller":"traceutil/trace.go:171","msg":"trace[1471715745] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"177.749514ms","start":"2026-02-24T04:31:40.016086Z","end":"2026-02-24T04:31:40.193835Z","steps":["trace[1471715745] 'process raft request' (duration: 177.580821ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:40.207858Z","caller":"traceutil/trace.go:171","msg":"trace[2068825014] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"190.324579ms","start":"2026-02-24T04:31:40.017509Z","end":"2026-02-24T04:31:40.207833Z","steps":["trace[2068825014] 'process raft request' (duration: 189.798419ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:57.649118Z","caller":"traceutil/trace.go:171","msg":"trace[620664459] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"148.952898ms","start":"2026-02-24T04:31:57.500148Z","end":"2026-02-24T04:31:57.649101Z","steps":["trace[620664459] 'read index received' (duration: 148.805656ms)","trace[620664459] 'applied index is now lower than readState.Index' (duration: 146.362µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:57.649219Z","caller":"traceutil/trace.go:171","msg":"trace[852384597] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"172.74351ms","start":"2026-02-24T04:31:57.476465Z","end":"2026-02-24T04:31:57.649208Z","steps":["trace[852384597] 'process raft request' (duration: 172.522016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:57.649409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.262353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:token-cleaner\" ","response":"range_response_count:1 size:729"} {"level":"info","ts":"2026-02-24T04:31:57.64944Z","caller":"traceutil/trace.go:171","msg":"trace[1141967321] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:token-cleaner; range_end:; response_count:1; response_revision:1078; }","duration":"149.319393ms","start":"2026-02-24T04:31:57.500109Z","end":"2026-02-24T04:31:57.649429Z","steps":["trace[1141967321] 'agreement among raft nodes before linearized reading' (duration: 149.262352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:57.649349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.48923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/cilium-config-agent\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-02-24T04:31:57.649596Z","caller":"traceutil/trace.go:171","msg":"trace[1486810649] range","detail":"{range_begin:/registry/roles/kube-system/cilium-config-agent; range_end:; response_count:1; response_revision:1078; }","duration":"146.753473ms","start":"2026-02-24T04:31:57.502826Z","end":"2026-02-24T04:31:57.649579Z","steps":["trace[1486810649] 'agreement among raft nodes before linearized reading' (duration: 146.436459ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:58.601679Z","caller":"traceutil/trace.go:171","msg":"trace[1021908433] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"106.997644ms","start":"2026-02-24T04:31:58.494655Z","end":"2026-02-24T04:31:58.601653Z","steps":["trace[1021908433] 'read index received' (duration: 106.831192ms)","trace[1021908433] 'applied index is now lower than readState.Index' (duration: 165.242µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:58.601749Z","caller":"traceutil/trace.go:171","msg":"trace[688517195] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"130.811574ms","start":"2026-02-24T04:31:58.470901Z","end":"2026-02-24T04:31:58.601712Z","steps":["trace[688517195] 'process raft request' (duration: 130.598281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:58.602013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.350079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T04:31:58.602046Z","caller":"traceutil/trace.go:171","msg":"trace[1703925073] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1083; }","duration":"107.43183ms","start":"2026-02-24T04:31:58.494606Z","end":"2026-02-24T04:31:58.602037Z","steps":["trace[1703925073] 'agreement among raft nodes before linearized reading' (duration: 107.168576ms)"],"step_count":1}