{"level":"warn","ts":"2026-03-29T12:57:01.778412Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T12:57:01.778517Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.221: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://162.253.55.221:2380","--initial-cluster=instance=https://162.253.55.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.221:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-29T12:57:01.778595Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T12:57:01.778608Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-29T12:57:01.778629Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-29T12:57:01.779325Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"]} {"level":"info","ts":"2026-03-29T12:57:01.77941Z","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://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.221:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-29T12:57:01.784132Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.489798ms"} {"level":"info","ts":"2026-03-29T12:57:01.79188Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c187bdee547e8fd1","cluster-id":"ee7528b898b8b18e"} {"level":"info","ts":"2026-03-29T12:57:01.791946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-29T12:57:01.791969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 0"} {"level":"info","ts":"2026-03-29T12:57:01.791984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c187bdee547e8fd1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-29T12:57:01.791994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 1"} {"level":"info","ts":"2026-03-29T12:57:01.792028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"warn","ts":"2026-03-29T12:57:01.79735Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-29T12:57:01.798779Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-29T12:57:01.800792Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-29T12:57:01.803984Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c187bdee547e8fd1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-29T12:57:01.804258Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c187bdee547e8fd1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-29T12:57:01.804286Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-29T12:57:01.804439Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-29T12:57:01.804458Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-29T12:57:01.805548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"info","ts":"2026-03-29T12:57:01.805759Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","added-peer-id":"c187bdee547e8fd1","added-peer-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-29T12:57:01.808839Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-29T12:57:01.809127Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c187bdee547e8fd1","initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-29T12:57:01.809166Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-29T12:57:01.809805Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-29T12:57:01.809852Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-29T12:57:02.592373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-29T12:57:02.592444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-29T12:57:02.592486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgPreVoteResp from c187bdee547e8fd1 at term 1"} {"level":"info","ts":"2026-03-29T12:57:02.59251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became candidate at term 2"} {"level":"info","ts":"2026-03-29T12:57:02.592527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgVoteResp from c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-29T12:57:02.592551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became leader at term 2"} {"level":"info","ts":"2026-03-29T12:57:02.592578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c187bdee547e8fd1 elected leader c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-29T12:57:02.598317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T12:57:02.598309Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c187bdee547e8fd1","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.221:2379]}","request-path":"/0/members/c187bdee547e8fd1/attributes","cluster-id":"ee7528b898b8b18e","publish-timeout":"7s"} {"level":"info","ts":"2026-03-29T12:57:02.598363Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T12:57:02.600472Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T12:57:02.600627Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T12:57:02.600672Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T12:57:02.600864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T12:57:02.602955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-29T12:57:02.613168Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-29T12:57:02.613199Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-29T12:57:02.614308Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.221:2379"} {"level":"warn","ts":"2026-03-29T12:57:04.14259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.657464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-29T12:57:04.142841Z","caller":"traceutil/trace.go:171","msg":"trace[6904382] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"138.033966ms","start":"2026-03-29T12:57:04.004786Z","end":"2026-03-29T12:57:04.14282Z","steps":["trace[6904382] 'process raft request' (duration: 24.547179ms)","trace[6904382] 'compare' (duration: 112.511882ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:04.309764Z","caller":"traceutil/trace.go:171","msg":"trace[1132630850] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"304.402815ms","start":"2026-03-29T12:57:04.005314Z","end":"2026-03-29T12:57:04.309716Z","steps":["trace[1132630850] 'process raft request' (duration: 304.248343ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:04.309813Z","caller":"traceutil/trace.go:171","msg":"trace[2097782296] transaction","detail":"{read_only:false; number_of_response:0; response_revision:10; }","duration":"302.347455ms","start":"2026-03-29T12:57:04.007427Z","end":"2026-03-29T12:57:04.309775Z","steps":["trace[2097782296] 'process raft request' (duration: 302.192372ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:04.309928Z","caller":"traceutil/trace.go:171","msg":"trace[1493876490] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"304.66343ms","start":"2026-03-29T12:57:04.005191Z","end":"2026-03-29T12:57:04.309854Z","steps":["trace[1493876490] 'process raft request' (duration: 304.254103ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:04.310028Z","caller":"traceutil/trace.go:171","msg":"trace[1137238325] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"304.739291ms","start":"2026-03-29T12:57:04.005268Z","end":"2026-03-29T12:57:04.310007Z","steps":["trace[1137238325] 'process raft request' (duration: 304.241894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.310034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.005279Z","time spent":"304.62884ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:04.310207Z","caller":"traceutil/trace.go:171","msg":"trace[1529437694] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"291.053615ms","start":"2026-03-29T12:57:04.019057Z","end":"2026-03-29T12:57:04.31011Z","steps":["trace[1529437694] 'process raft request' (duration: 290.631039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.310284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.005253Z","time spent":"304.867443ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:04.310286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.00517Z","time spent":"304.811682ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:04.310396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.012023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T12:57:04.310468Z","caller":"traceutil/trace.go:171","msg":"trace[944798105] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"273.146345ms","start":"2026-03-29T12:57:04.037302Z","end":"2026-03-29T12:57:04.310449Z","steps":["trace[944798105] 'agreement among raft nodes before linearized reading' (duration: 272.889162ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:04.310522Z","caller":"traceutil/trace.go:171","msg":"trace[258975007] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"304.341864ms","start":"2026-03-29T12:57:04.005314Z","end":"2026-03-29T12:57:04.309656Z","steps":["trace[258975007] 'process raft request' (duration: 304.221873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.310698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.005303Z","time spent":"305.311089ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:04.31078Z","caller":"traceutil/trace.go:171","msg":"trace[1213108622] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"287.906297ms","start":"2026-03-29T12:57:04.022858Z","end":"2026-03-29T12:57:04.310764Z","steps":["trace[1213108622] 'process raft request' (duration: 287.135985ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:04.31014Z","caller":"traceutil/trace.go:171","msg":"trace[81805627] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:7; }","duration":"272.77459ms","start":"2026-03-29T12:57:04.03732Z","end":"2026-03-29T12:57:04.310094Z","steps":["trace[81805627] 'read index received' (duration: 269.818835ms)","trace[81805627] 'applied index is now lower than readState.Index' (duration: 2.954685ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:04.310871Z","caller":"traceutil/trace.go:171","msg":"trace[943311931] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"304.224113ms","start":"2026-03-29T12:57:04.006628Z","end":"2026-03-29T12:57:04.310853Z","steps":["trace[943311931] 'process raft request' (duration: 302.960954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.311017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.006609Z","time spent":"304.320765ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:04.311484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.007408Z","time spent":"304.03646ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:04.309602Z","caller":"traceutil/trace.go:171","msg":"trace[798653745] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"304.453477ms","start":"2026-03-29T12:57:04.005096Z","end":"2026-03-29T12:57:04.309549Z","steps":["trace[798653745] 'process raft request' (duration: 302.02627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.314043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.005079Z","time spent":"308.918914ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:04.327753Z","caller":"traceutil/trace.go:171","msg":"trace[209513035] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"181.354215ms","start":"2026-03-29T12:57:04.146383Z","end":"2026-03-29T12:57:04.327737Z","steps":["trace[209513035] 'process raft request' (duration: 181.228444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.327852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.756431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T12:57:04.327944Z","caller":"traceutil/trace.go:171","msg":"trace[804908386] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:13; }","duration":"141.837981ms","start":"2026-03-29T12:57:04.186068Z","end":"2026-03-29T12:57:04.327906Z","steps":["trace[804908386] 'agreement among raft nodes before linearized reading' (duration: 141.680219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.328141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.843661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-29T12:57:04.328206Z","caller":"traceutil/trace.go:171","msg":"trace[523192665] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"272.952723ms","start":"2026-03-29T12:57:04.055237Z","end":"2026-03-29T12:57:04.328189Z","steps":["trace[523192665] 'agreement among raft nodes before linearized reading' (duration: 272.657658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.776235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.502429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-29T12:57:04.776464Z","caller":"traceutil/trace.go:171","msg":"trace[458126590] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"461.732168ms","start":"2026-03-29T12:57:04.314703Z","end":"2026-03-29T12:57:04.776435Z","steps":["trace[458126590] 'process raft request' (duration: 225.944215ms)","trace[458126590] 'compare' (duration: 235.332757ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:04.776583Z","caller":"traceutil/trace.go:171","msg":"trace[681224338] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"460.870376ms","start":"2026-03-29T12:57:04.315671Z","end":"2026-03-29T12:57:04.776541Z","steps":["trace[681224338] 'process raft request' (duration: 460.663423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.776693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.314672Z","time spent":"461.969792ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:04.776745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.315661Z","time spent":"461.007898ms","remote":"127.0.0.1:38772","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-03-29T12:57:04.776884Z","caller":"traceutil/trace.go:171","msg":"trace[878908563] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"460.855086ms","start":"2026-03-29T12:57:04.316021Z","end":"2026-03-29T12:57:04.776876Z","steps":["trace[878908563] 'process raft request' (duration: 460.356089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.77693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.315999Z","time spent":"460.908177ms","remote":"127.0.0.1:38772","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-03-29T12:57:04.777015Z","caller":"traceutil/trace.go:171","msg":"trace[180647661] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"459.994372ms","start":"2026-03-29T12:57:04.317014Z","end":"2026-03-29T12:57:04.777008Z","steps":["trace[180647661] 'process raft request' (duration: 459.445054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.777051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.317008Z","time spent":"460.028864ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:04.777448Z","caller":"traceutil/trace.go:171","msg":"trace[294991738] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"460.339608ms","start":"2026-03-29T12:57:04.317083Z","end":"2026-03-29T12:57:04.777422Z","steps":["trace[294991738] 'process raft request' (duration: 459.553856ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:04.777546Z","caller":"traceutil/trace.go:171","msg":"trace[1478991230] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:17; }","duration":"449.82037ms","start":"2026-03-29T12:57:04.32771Z","end":"2026-03-29T12:57:04.77753Z","steps":["trace[1478991230] 'read index received' (duration: 212.954701ms)","trace[1478991230] 'applied index is now lower than readState.Index' (duration: 236.864399ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:04.777555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.317068Z","time spent":"460.453839ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:04.777827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.164041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-03-29T12:57:04.777835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.252436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-hg2xj\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-29T12:57:04.777909Z","caller":"traceutil/trace.go:171","msg":"trace[1520479291] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-hg2xj; range_end:; response_count:1; response_revision:19; }","duration":"462.340348ms","start":"2026-03-29T12:57:04.315553Z","end":"2026-03-29T12:57:04.777894Z","steps":["trace[1520479291] 'agreement among raft nodes before linearized reading' (duration: 462.236756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.777954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.315546Z","time spent":"462.394638ms","remote":"127.0.0.1:38552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-hg2xj\" "} {"level":"info","ts":"2026-03-29T12:57:04.778087Z","caller":"traceutil/trace.go:171","msg":"trace[966685841] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"455.810359ms","start":"2026-03-29T12:57:04.322236Z","end":"2026-03-29T12:57:04.778046Z","steps":["trace[966685841] 'process raft request' (duration: 454.843215ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:04.777898Z","caller":"traceutil/trace.go:171","msg":"trace[757364161] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"463.280802ms","start":"2026-03-29T12:57:04.314606Z","end":"2026-03-29T12:57:04.777886Z","steps":["trace[757364161] 'agreement among raft nodes before linearized reading' (duration: 463.069479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.778242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.093269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-29T12:57:04.778287Z","caller":"traceutil/trace.go:171","msg":"trace[1037810360] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"463.185731ms","start":"2026-03-29T12:57:04.31509Z","end":"2026-03-29T12:57:04.778276Z","steps":["trace[1037810360] 'agreement among raft nodes before linearized reading' (duration: 463.059089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.778312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.315072Z","time spent":"463.234802ms","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-29T12:57:04.778713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.322228Z","time spent":"455.938691ms","remote":"127.0.0.1:38468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T12:57:04.777893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.408793ms","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-03-29T12:57:04.778963Z","caller":"traceutil/trace.go:171","msg":"trace[1913183945] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:19; }","duration":"462.597002ms","start":"2026-03-29T12:57:04.316347Z","end":"2026-03-29T12:57:04.778944Z","steps":["trace[1913183945] 'agreement among raft nodes before linearized reading' (duration: 461.360623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.779178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.316337Z","time spent":"462.744654ms","remote":"127.0.0.1:38394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-03-29T12:57:04.779779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.314587Z","time spent":"465.17525ms","remote":"127.0.0.1:38468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-29T12:57:04.788985Z","caller":"traceutil/trace.go:171","msg":"trace[350019742] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"457.266632ms","start":"2026-03-29T12:57:04.331705Z","end":"2026-03-29T12:57:04.788972Z","steps":["trace[350019742] 'process raft request' (duration: 457.093569ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:04.788985Z","caller":"traceutil/trace.go:171","msg":"trace[1118833783] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"458.766165ms","start":"2026-03-29T12:57:04.330164Z","end":"2026-03-29T12:57:04.788931Z","steps":["trace[1118833783] 'process raft request' (duration: 458.519451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.789051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.33168Z","time spent":"457.339093ms","remote":"127.0.0.1:38704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:04.78906Z","caller":"traceutil/trace.go:171","msg":"trace[498537514] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"457.20568ms","start":"2026-03-29T12:57:04.331841Z","end":"2026-03-29T12:57:04.789047Z","steps":["trace[498537514] 'process raft request' (duration: 456.992107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:04.789132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.331824Z","time spent":"457.272112ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:04.789403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:04.330131Z","time spent":"458.925186ms","remote":"127.0.0.1:38414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:05.024451Z","caller":"traceutil/trace.go:171","msg":"trace[342328641] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"232.257199ms","start":"2026-03-29T12:57:04.792168Z","end":"2026-03-29T12:57:05.024426Z","steps":["trace[342328641] 'process raft request' (duration: 232.198128ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.024576Z","caller":"traceutil/trace.go:171","msg":"trace[1494487900] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"232.758016ms","start":"2026-03-29T12:57:04.791773Z","end":"2026-03-29T12:57:05.024531Z","steps":["trace[1494487900] 'process raft request' (duration: 183.372624ms)","trace[1494487900] 'compare' (duration: 49.034027ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:05.042211Z","caller":"traceutil/trace.go:171","msg":"trace[1958133076] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"187.314793ms","start":"2026-03-29T12:57:04.854856Z","end":"2026-03-29T12:57:05.042171Z","steps":["trace[1958133076] 'process raft request' (duration: 187.11894ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.04226Z","caller":"traceutil/trace.go:171","msg":"trace[1903395434] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"186.322067ms","start":"2026-03-29T12:57:04.85592Z","end":"2026-03-29T12:57:05.042242Z","steps":["trace[1903395434] 'process raft request' (duration: 186.214455ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.042343Z","caller":"traceutil/trace.go:171","msg":"trace[519890315] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"186.309597ms","start":"2026-03-29T12:57:04.856016Z","end":"2026-03-29T12:57:05.042326Z","steps":["trace[519890315] 'process raft request' (duration: 186.148025ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.042231Z","caller":"traceutil/trace.go:171","msg":"trace[2033193359] linearizableReadLoop","detail":"{readStateIndex:39; appliedIndex:33; }","duration":"185.971042ms","start":"2026-03-29T12:57:04.856236Z","end":"2026-03-29T12:57:05.042207Z","steps":["trace[2033193359] 'read index received' (duration: 118.924366ms)","trace[2033193359] 'applied index is now lower than readState.Index' (duration: 67.045636ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:05.042426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.136104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T12:57:05.042459Z","caller":"traceutil/trace.go:171","msg":"trace[534821936] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"186.040602ms","start":"2026-03-29T12:57:04.856402Z","end":"2026-03-29T12:57:05.042443Z","steps":["trace[534821936] 'process raft request' (duration: 185.782309ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.042496Z","caller":"traceutil/trace.go:171","msg":"trace[2007139707] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:36; }","duration":"186.286616ms","start":"2026-03-29T12:57:04.856195Z","end":"2026-03-29T12:57:05.042482Z","steps":["trace[2007139707] 'agreement among raft nodes before linearized reading' (duration: 186.080223ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.042418Z","caller":"traceutil/trace.go:171","msg":"trace[7737318] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"186.577981ms","start":"2026-03-29T12:57:04.855804Z","end":"2026-03-29T12:57:05.042382Z","steps":["trace[7737318] 'process raft request' (duration: 186.303406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:05.065924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.503682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T12:57:05.066022Z","caller":"traceutil/trace.go:171","msg":"trace[751619796] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:37; }","duration":"163.704706ms","start":"2026-03-29T12:57:04.902299Z","end":"2026-03-29T12:57:05.066004Z","steps":["trace[751619796] 'agreement among raft nodes before linearized reading' (duration: 163.460353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:05.066018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.273924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T12:57:05.06609Z","caller":"traceutil/trace.go:171","msg":"trace[1388648731] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:37; }","duration":"162.349256ms","start":"2026-03-29T12:57:04.903728Z","end":"2026-03-29T12:57:05.066077Z","steps":["trace[1388648731] 'agreement among raft nodes before linearized reading' (duration: 162.248534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:05.318119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.541674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-29T12:57:05.318347Z","caller":"traceutil/trace.go:171","msg":"trace[2025659283] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:43; }","duration":"237.650736ms","start":"2026-03-29T12:57:05.080652Z","end":"2026-03-29T12:57:05.318303Z","steps":["trace[2025659283] 'read index received' (duration: 136.738546ms)","trace[2025659283] 'applied index is now lower than readState.Index' (duration: 100.91113ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:05.318538Z","caller":"traceutil/trace.go:171","msg":"trace[2074716520] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"249.068726ms","start":"2026-03-29T12:57:05.069431Z","end":"2026-03-29T12:57:05.3185Z","steps":["trace[2074716520] 'process raft request' (duration: 148.070665ms)","trace[2074716520] 'compare' (duration: 100.421331ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:05.318672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.693906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T12:57:05.318754Z","caller":"traceutil/trace.go:171","msg":"trace[863822097] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:41; }","duration":"233.778736ms","start":"2026-03-29T12:57:05.084931Z","end":"2026-03-29T12:57:05.31871Z","steps":["trace[863822097] 'agreement among raft nodes before linearized reading' (duration: 233.533543ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.319101Z","caller":"traceutil/trace.go:171","msg":"trace[363483923] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"249.564384ms","start":"2026-03-29T12:57:05.069475Z","end":"2026-03-29T12:57:05.319039Z","steps":["trace[363483923] 'process raft request' (duration: 248.731631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:05.31946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.628024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T12:57:05.319518Z","caller":"traceutil/trace.go:171","msg":"trace[380808065] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:41; }","duration":"250.428487ms","start":"2026-03-29T12:57:05.069076Z","end":"2026-03-29T12:57:05.319504Z","steps":["trace[380808065] 'agreement among raft nodes before linearized reading' (duration: 249.450072ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.692162Z","caller":"traceutil/trace.go:171","msg":"trace[265563199] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"183.365425ms","start":"2026-03-29T12:57:05.508758Z","end":"2026-03-29T12:57:05.692124Z","steps":["trace[265563199] 'process raft request' (duration: 126.815699ms)","trace[265563199] 'compare' (duration: 56.378793ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:05.695756Z","caller":"traceutil/trace.go:171","msg":"trace[1206922224] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"186.907946ms","start":"2026-03-29T12:57:05.508807Z","end":"2026-03-29T12:57:05.695715Z","steps":["trace[1206922224] 'process raft request' (duration: 186.574802ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.695899Z","caller":"traceutil/trace.go:171","msg":"trace[1271743904] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"185.210862ms","start":"2026-03-29T12:57:05.510654Z","end":"2026-03-29T12:57:05.695864Z","steps":["trace[1271743904] 'process raft request' (duration: 184.981788ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.92423Z","caller":"traceutil/trace.go:171","msg":"trace[1510523785] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"130.499133ms","start":"2026-03-29T12:57:05.793702Z","end":"2026-03-29T12:57:05.924201Z","steps":["trace[1510523785] 'process raft request' (duration: 130.449892ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:05.924269Z","caller":"traceutil/trace.go:171","msg":"trace[102688604] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"159.879222ms","start":"2026-03-29T12:57:05.764358Z","end":"2026-03-29T12:57:05.924238Z","steps":["trace[102688604] 'process raft request' (duration: 141.393955ms)","trace[102688604] 'compare' (duration: 18.229753ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:05.924298Z","caller":"traceutil/trace.go:171","msg":"trace[1415383130] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"159.884643ms","start":"2026-03-29T12:57:05.764387Z","end":"2026-03-29T12:57:05.924272Z","steps":["trace[1415383130] 'process raft request' (duration: 159.69064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:06.151611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.536519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-29T12:57:06.151891Z","caller":"traceutil/trace.go:171","msg":"trace[194636228] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"130.283164ms","start":"2026-03-29T12:57:06.021574Z","end":"2026-03-29T12:57:06.151857Z","steps":["trace[194636228] 'process raft request' (duration: 19.394109ms)","trace[194636228] 'compare' (duration: 110.409117ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:06.152036Z","caller":"traceutil/trace.go:171","msg":"trace[1209325501] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"127.814117ms","start":"2026-03-29T12:57:06.024147Z","end":"2026-03-29T12:57:06.151962Z","steps":["trace[1209325501] 'process raft request' (duration: 127.674054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:06.152237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.217313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-29T12:57:06.152306Z","caller":"traceutil/trace.go:171","msg":"trace[1893124783] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:68; }","duration":"128.326964ms","start":"2026-03-29T12:57:06.023963Z","end":"2026-03-29T12:57:06.15229Z","steps":["trace[1893124783] 'agreement among raft nodes before linearized reading' (duration: 128.159851ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:06.152052Z","caller":"traceutil/trace.go:171","msg":"trace[2040946082] linearizableReadLoop","detail":"{readStateIndex:71; appliedIndex:70; }","duration":"127.855057ms","start":"2026-03-29T12:57:06.024151Z","end":"2026-03-29T12:57:06.152006Z","steps":["trace[2040946082] 'read index received' (duration: 16.827791ms)","trace[2040946082] 'applied index is now lower than readState.Index' (duration: 111.025156ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:06.347636Z","caller":"traceutil/trace.go:171","msg":"trace[1799490954] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"110.554687ms","start":"2026-03-29T12:57:06.237054Z","end":"2026-03-29T12:57:06.347608Z","steps":["trace[1799490954] 'process raft request' (duration: 110.340004ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:06.347716Z","caller":"traceutil/trace.go:171","msg":"trace[1135090969] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"110.462887ms","start":"2026-03-29T12:57:06.237221Z","end":"2026-03-29T12:57:06.347684Z","steps":["trace[1135090969] 'process raft request' (duration: 110.331645ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:06.34771Z","caller":"traceutil/trace.go:171","msg":"trace[1077907677] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"107.42133ms","start":"2026-03-29T12:57:06.240264Z","end":"2026-03-29T12:57:06.347685Z","steps":["trace[1077907677] 'process raft request' (duration: 107.36623ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:06.348328Z","caller":"traceutil/trace.go:171","msg":"trace[1590537590] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"110.765231ms","start":"2026-03-29T12:57:06.237548Z","end":"2026-03-29T12:57:06.348313Z","steps":["trace[1590537590] 'process raft request' (duration: 110.0483ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:06.727465Z","caller":"traceutil/trace.go:171","msg":"trace[1328629074] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"166.048295ms","start":"2026-03-29T12:57:06.561377Z","end":"2026-03-29T12:57:06.727425Z","steps":["trace[1328629074] 'process raft request' (duration: 66.41753ms)","trace[1328629074] 'compare' (duration: 99.368501ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:06.72756Z","caller":"traceutil/trace.go:171","msg":"trace[730636892] linearizableReadLoop","detail":"{readStateIndex:96; appliedIndex:94; }","duration":"156.590604ms","start":"2026-03-29T12:57:06.570926Z","end":"2026-03-29T12:57:06.727517Z","steps":["trace[730636892] 'read index received' (duration: 56.918109ms)","trace[730636892] 'applied index is now lower than readState.Index' (duration: 99.671195ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:06.727736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.801627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a1513617e6576f\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-03-29T12:57:06.727785Z","caller":"traceutil/trace.go:171","msg":"trace[711356447] range","detail":"{range_begin:/registry/events/default/instance.18a1513617e6576f; range_end:; response_count:1; response_revision:91; }","duration":"156.919089ms","start":"2026-03-29T12:57:06.570851Z","end":"2026-03-29T12:57:06.72777Z","steps":["trace[711356447] 'agreement among raft nodes before linearized reading' (duration: 156.767307ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:06.727833Z","caller":"traceutil/trace.go:171","msg":"trace[954198080] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"157.61757ms","start":"2026-03-29T12:57:06.570199Z","end":"2026-03-29T12:57:06.727816Z","steps":["trace[954198080] 'process raft request' (duration: 157.105922ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:08.477699Z","caller":"traceutil/trace.go:171","msg":"trace[154885302] linearizableReadLoop","detail":"{readStateIndex:166; appliedIndex:164; }","duration":"268.973513ms","start":"2026-03-29T12:57:08.20866Z","end":"2026-03-29T12:57:08.477633Z","steps":["trace[154885302] 'read index received' (duration: 219.913096ms)","trace[154885302] 'applied index is now lower than readState.Index' (duration: 49.058917ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:08.477834Z","caller":"traceutil/trace.go:171","msg":"trace[577868738] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"430.570884ms","start":"2026-03-29T12:57:08.047232Z","end":"2026-03-29T12:57:08.477803Z","steps":["trace[577868738] 'process raft request' (duration: 381.321204ms)","trace[577868738] 'compare' (duration: 48.64825ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:08.478328Z","caller":"traceutil/trace.go:171","msg":"trace[1268100999] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"431.016441ms","start":"2026-03-29T12:57:08.047293Z","end":"2026-03-29T12:57:08.47831Z","steps":["trace[1268100999] 'process raft request' (duration: 430.227799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:08.478451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.771174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:08.478554Z","caller":"traceutil/trace.go:171","msg":"trace[1999844181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:161; }","duration":"269.943896ms","start":"2026-03-29T12:57:08.208583Z","end":"2026-03-29T12:57:08.478527Z","steps":["trace[1999844181] 'agreement among raft nodes before linearized reading' (duration: 269.769064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:08.478581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:08.047215Z","time spent":"431.275915ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:08.478622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:08.047276Z","time spent":"431.249754ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T12:57:08.699003Z","caller":"traceutil/trace.go:171","msg":"trace[1123269041] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"212.385844ms","start":"2026-03-29T12:57:08.486583Z","end":"2026-03-29T12:57:08.698969Z","steps":["trace[1123269041] 'process raft request' (duration: 152.170432ms)","trace[1123269041] 'compare' (duration: 60.06187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:09.232518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.380574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T12:57:09.232769Z","caller":"traceutil/trace.go:171","msg":"trace[1710838000] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"461.504099ms","start":"2026-03-29T12:57:08.771242Z","end":"2026-03-29T12:57:09.232747Z","steps":["trace[1710838000] 'process raft request' (duration: 254.80116ms)","trace[1710838000] 'compare' (duration: 206.180561ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:09.232765Z","caller":"traceutil/trace.go:171","msg":"trace[126897200] linearizableReadLoop","detail":"{readStateIndex:170; appliedIndex:169; }","duration":"260.015725ms","start":"2026-03-29T12:57:08.97267Z","end":"2026-03-29T12:57:09.232686Z","steps":["trace[126897200] 'read index received' (duration: 53.312807ms)","trace[126897200] 'applied index is now lower than readState.Index' (duration: 206.699828ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:09.232856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:08.771213Z","time spent":"461.61843ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:09.233076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.390811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:09.233126Z","caller":"traceutil/trace.go:171","msg":"trace[2031733043] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:165; }","duration":"260.493453ms","start":"2026-03-29T12:57:08.972618Z","end":"2026-03-29T12:57:09.233111Z","steps":["trace[2031733043] 'agreement among raft nodes before linearized reading' (duration: 260.353241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:09.233193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.733199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:09.233234Z","caller":"traceutil/trace.go:171","msg":"trace[111348507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:165; }","duration":"124.831602ms","start":"2026-03-29T12:57:09.108392Z","end":"2026-03-29T12:57:09.233224Z","steps":["trace[111348507] 'agreement among raft nodes before linearized reading' (duration: 124.760241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:09.621706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.186418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T12:57:09.621853Z","caller":"traceutil/trace.go:171","msg":"trace[1466062413] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"381.905032ms","start":"2026-03-29T12:57:09.239925Z","end":"2026-03-29T12:57:09.62183Z","steps":["trace[1466062413] 'process raft request' (duration: 104.478351ms)","trace[1466062413] 'compare' (duration: 276.958154ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:09.621951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:09.239899Z","time spent":"382.026113ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:10.028461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.612752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T12:57:10.028579Z","caller":"traceutil/trace.go:171","msg":"trace[453966235] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"309.97714ms","start":"2026-03-29T12:57:09.718582Z","end":"2026-03-29T12:57:10.028559Z","steps":["trace[453966235] 'process raft request' (duration: 87.187906ms)","trace[453966235] 'compare' (duration: 222.436789ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:10.028651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:09.718534Z","time spent":"310.094781ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":650,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:10.384424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.682812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T12:57:10.384529Z","caller":"traceutil/trace.go:171","msg":"trace[385688477] linearizableReadLoop","detail":"{readStateIndex:175; appliedIndex:174; }","duration":"176.452175ms","start":"2026-03-29T12:57:10.20806Z","end":"2026-03-29T12:57:10.384512Z","steps":["trace[385688477] 'read index received' (duration: 39.28µs)","trace[385688477] 'applied index is now lower than readState.Index' (duration: 176.409025ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:10.38464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.585856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:10.384673Z","caller":"traceutil/trace.go:171","msg":"trace[2053279925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:170; }","duration":"176.660117ms","start":"2026-03-29T12:57:10.208003Z","end":"2026-03-29T12:57:10.384663Z","steps":["trace[2053279925] 'agreement among raft nodes before linearized reading' (duration: 176.554495ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:10.384655Z","caller":"traceutil/trace.go:171","msg":"trace[849395244] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"350.356868ms","start":"2026-03-29T12:57:10.034246Z","end":"2026-03-29T12:57:10.384603Z","steps":["trace[849395244] 'process raft request' (duration: 142.426573ms)","trace[849395244] 'compare' (duration: 207.55201ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:10.384898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:10.034213Z","time spent":"350.608472ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":828,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:10.927921Z","caller":"traceutil/trace.go:171","msg":"trace[168569951] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:178; }","duration":"369.596591ms","start":"2026-03-29T12:57:10.558282Z","end":"2026-03-29T12:57:10.927878Z","steps":["trace[168569951] 'read index received' (duration: 35.637663ms)","trace[168569951] 'applied index is now lower than readState.Index' (duration: 333.957538ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:10.92814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.829664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T12:57:10.928143Z","caller":"traceutil/trace.go:171","msg":"trace[1643079699] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"370.717908ms","start":"2026-03-29T12:57:10.557335Z","end":"2026-03-29T12:57:10.928053Z","steps":["trace[1643079699] 'process raft request' (duration: 295.052005ms)","trace[1643079699] 'compare' (duration: 75.233996ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:10.928233Z","caller":"traceutil/trace.go:171","msg":"trace[635520072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:176; }","duration":"369.961166ms","start":"2026-03-29T12:57:10.558254Z","end":"2026-03-29T12:57:10.928215Z","steps":["trace[635520072] 'agreement among raft nodes before linearized reading' (duration: 369.759583ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:10.928188Z","caller":"traceutil/trace.go:171","msg":"trace[1572169019] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"342.436352ms","start":"2026-03-29T12:57:10.585661Z","end":"2026-03-29T12:57:10.928097Z","steps":["trace[1572169019] 'process raft request' (duration: 342.151188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:10.92841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:10.557309Z","time spent":"370.989961ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":662,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:10.928326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:10.558245Z","time spent":"370.059857ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T12:57:10.928526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:10.585637Z","time spent":"342.780397ms","remote":"127.0.0.1:38484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:11.156054Z","caller":"traceutil/trace.go:171","msg":"trace[1233851363] linearizableReadLoop","detail":"{readStateIndex:183; appliedIndex:182; }","duration":"146.500225ms","start":"2026-03-29T12:57:11.009527Z","end":"2026-03-29T12:57:11.156028Z","steps":["trace[1233851363] 'read index received' (duration: 65.650891ms)","trace[1233851363] 'applied index is now lower than readState.Index' (duration: 80.848004ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:11.156114Z","caller":"traceutil/trace.go:171","msg":"trace[1150020032] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"220.685225ms","start":"2026-03-29T12:57:10.935391Z","end":"2026-03-29T12:57:11.156076Z","steps":["trace[1150020032] 'process raft request' (duration: 139.846861ms)","trace[1150020032] 'compare' (duration: 80.632981ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:11.156269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.707328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-03-29T12:57:11.15632Z","caller":"traceutil/trace.go:171","msg":"trace[1067286343] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:178; }","duration":"146.8441ms","start":"2026-03-29T12:57:11.009461Z","end":"2026-03-29T12:57:11.156305Z","steps":["trace[1067286343] 'agreement among raft nodes before linearized reading' (duration: 146.682918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:11.494822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.071718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T12:57:11.494921Z","caller":"traceutil/trace.go:171","msg":"trace[458400527] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"332.696081ms","start":"2026-03-29T12:57:11.162208Z","end":"2026-03-29T12:57:11.494904Z","steps":["trace[458400527] 'process raft request' (duration: 126.453841ms)","trace[458400527] 'compare' (duration: 205.956907ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:11.494973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:11.162171Z","time spent":"332.780324ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":975,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:11.49605Z","caller":"traceutil/trace.go:171","msg":"trace[342301842] linearizableReadLoop","detail":"{readStateIndex:185; appliedIndex:183; }","duration":"287.395869ms","start":"2026-03-29T12:57:11.208637Z","end":"2026-03-29T12:57:11.496033Z","steps":["trace[342301842] 'read index received' (duration: 80.021812ms)","trace[342301842] 'applied index is now lower than readState.Index' (duration: 207.372957ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:11.496158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.509781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:11.496199Z","caller":"traceutil/trace.go:171","msg":"trace[30424141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:180; }","duration":"287.586511ms","start":"2026-03-29T12:57:11.208599Z","end":"2026-03-29T12:57:11.496186Z","steps":["trace[30424141] 'agreement among raft nodes before linearized reading' (duration: 287.50165ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:11.496503Z","caller":"traceutil/trace.go:171","msg":"trace[220049563] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"315.941767ms","start":"2026-03-29T12:57:11.180549Z","end":"2026-03-29T12:57:11.496491Z","steps":["trace[220049563] 'process raft request' (duration: 315.383419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:11.496579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:11.180526Z","time spent":"316.011647ms","remote":"127.0.0.1:38484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T12:57:11.778252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.64695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T12:57:11.778405Z","caller":"traceutil/trace.go:171","msg":"trace[1872966425] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"277.275601ms","start":"2026-03-29T12:57:11.501107Z","end":"2026-03-29T12:57:11.778382Z","steps":["trace[1872966425] 'process raft request' (duration: 112.405166ms)","trace[1872966425] 'compare' (duration: 164.439778ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:11.814923Z","caller":"traceutil/trace.go:171","msg":"trace[1772070207] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"308.070392ms","start":"2026-03-29T12:57:11.506829Z","end":"2026-03-29T12:57:11.814899Z","steps":["trace[1772070207] 'process raft request' (duration: 307.795237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:11.81507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:11.506802Z","time spent":"308.184462ms","remote":"127.0.0.1:38484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T12:57:12.008566Z","caller":"traceutil/trace.go:171","msg":"trace[1557722660] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"168.276654ms","start":"2026-03-29T12:57:11.840257Z","end":"2026-03-29T12:57:12.008534Z","steps":["trace[1557722660] 'process raft request' (duration: 68.417112ms)","trace[1557722660] 'compare' (duration: 99.653089ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:12.414612Z","caller":"traceutil/trace.go:171","msg":"trace[172131369] linearizableReadLoop","detail":"{readStateIndex:198; appliedIndex:197; }","duration":"106.558724ms","start":"2026-03-29T12:57:12.308028Z","end":"2026-03-29T12:57:12.414587Z","steps":["trace[172131369] 'read index received' (duration: 48.479007ms)","trace[172131369] 'applied index is now lower than readState.Index' (duration: 58.078587ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:12.414771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.715016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:12.414814Z","caller":"traceutil/trace.go:171","msg":"trace[1881274727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:193; }","duration":"106.819879ms","start":"2026-03-29T12:57:12.307983Z","end":"2026-03-29T12:57:12.414803Z","steps":["trace[1881274727] 'agreement among raft nodes before linearized reading' (duration: 106.700528ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:12.414697Z","caller":"traceutil/trace.go:171","msg":"trace[1908639072] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"127.386759ms","start":"2026-03-29T12:57:12.287247Z","end":"2026-03-29T12:57:12.414634Z","steps":["trace[1908639072] 'process raft request' (duration: 69.22372ms)","trace[1908639072] 'compare' (duration: 58.005987ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:12.876911Z","caller":"traceutil/trace.go:171","msg":"trace[594322363] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"201.851426ms","start":"2026-03-29T12:57:12.675026Z","end":"2026-03-29T12:57:12.876878Z","steps":["trace[594322363] 'process raft request' (duration: 185.224823ms)","trace[594322363] 'compare' (duration: 16.494182ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:16.279358Z","caller":"traceutil/trace.go:171","msg":"trace[776900592] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"185.397403ms","start":"2026-03-29T12:57:16.093929Z","end":"2026-03-29T12:57:16.279327Z","steps":["trace[776900592] 'process raft request' (duration: 149.353174ms)","trace[776900592] 'compare' (duration: 35.897317ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:16.299805Z","caller":"traceutil/trace.go:171","msg":"trace[1645311916] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"205.793288ms","start":"2026-03-29T12:57:16.09399Z","end":"2026-03-29T12:57:16.299784Z","steps":["trace[1645311916] 'process raft request' (duration: 205.625575ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:16.299911Z","caller":"traceutil/trace.go:171","msg":"trace[1003359930] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"205.926989ms","start":"2026-03-29T12:57:16.093935Z","end":"2026-03-29T12:57:16.299862Z","steps":["trace[1003359930] 'process raft request' (duration: 205.562364ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:16.299929Z","caller":"traceutil/trace.go:171","msg":"trace[293169906] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"152.314996ms","start":"2026-03-29T12:57:16.147586Z","end":"2026-03-29T12:57:16.2999Z","steps":["trace[293169906] 'process raft request' (duration: 152.128323ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:16.300005Z","caller":"traceutil/trace.go:171","msg":"trace[582010107] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"150.652751ms","start":"2026-03-29T12:57:16.149343Z","end":"2026-03-29T12:57:16.299996Z","steps":["trace[582010107] 'process raft request' (duration: 150.479399ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:16.300072Z","caller":"traceutil/trace.go:171","msg":"trace[604811879] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"148.658204ms","start":"2026-03-29T12:57:16.151378Z","end":"2026-03-29T12:57:16.300036Z","steps":["trace[604811879] 'process raft request' (duration: 148.507391ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:16.299955Z","caller":"traceutil/trace.go:171","msg":"trace[1421711421] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:308; }","duration":"205.852948ms","start":"2026-03-29T12:57:16.094068Z","end":"2026-03-29T12:57:16.299921Z","steps":["trace[1421711421] 'read index received' (duration: 149.367953ms)","trace[1421711421] 'applied index is now lower than readState.Index' (duration: 56.483075ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:16.300128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.03421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-29T12:57:16.300185Z","caller":"traceutil/trace.go:171","msg":"trace[2313110] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:306; }","duration":"206.149102ms","start":"2026-03-29T12:57:16.094023Z","end":"2026-03-29T12:57:16.300172Z","steps":["trace[2313110] 'agreement among raft nodes before linearized reading' (duration: 206.00707ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:16.300154Z","caller":"traceutil/trace.go:171","msg":"trace[1702694067] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"166.684123ms","start":"2026-03-29T12:57:16.133439Z","end":"2026-03-29T12:57:16.300123Z","steps":["trace[1702694067] 'process raft request' (duration: 166.240177ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:16.29995Z","caller":"traceutil/trace.go:171","msg":"trace[704768910] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"148.697395ms","start":"2026-03-29T12:57:16.151241Z","end":"2026-03-29T12:57:16.299938Z","steps":["trace[704768910] 'process raft request' (duration: 148.618013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:16.300353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.204182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-29T12:57:16.300435Z","caller":"traceutil/trace.go:171","msg":"trace[478332554] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:306; }","duration":"206.353035ms","start":"2026-03-29T12:57:16.094061Z","end":"2026-03-29T12:57:16.300414Z","steps":["trace[478332554] 'agreement among raft nodes before linearized reading' (duration: 206.173293ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:16.299974Z","caller":"traceutil/trace.go:171","msg":"trace[1503041856] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"150.700593ms","start":"2026-03-29T12:57:16.149236Z","end":"2026-03-29T12:57:16.299937Z","steps":["trace[1503041856] 'process raft request' (duration: 150.50956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:17.301046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.988399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-29T12:57:17.301178Z","caller":"traceutil/trace.go:171","msg":"trace[117073782] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:325; }","duration":"233.171212ms","start":"2026-03-29T12:57:17.067983Z","end":"2026-03-29T12:57:17.301154Z","steps":["trace[117073782] 'range keys from in-memory index tree' (duration: 232.906758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:17.301053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.020507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-03-29T12:57:17.301308Z","caller":"traceutil/trace.go:171","msg":"trace[916799594] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:325; }","duration":"230.354152ms","start":"2026-03-29T12:57:17.070927Z","end":"2026-03-29T12:57:17.301281Z","steps":["trace[916799594] 'range keys from in-memory index tree' (duration: 229.893335ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:17.301836Z","caller":"traceutil/trace.go:171","msg":"trace[258087655] transaction","detail":"{read_only:false; number_of_response:0; response_revision:325; }","duration":"230.21155ms","start":"2026-03-29T12:57:17.071611Z","end":"2026-03-29T12:57:17.301823Z","steps":["trace[258087655] 'process raft request' (duration: 230.117878ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:17.39331Z","caller":"traceutil/trace.go:171","msg":"trace[2077869998] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:336; }","duration":"317.369413ms","start":"2026-03-29T12:57:17.07588Z","end":"2026-03-29T12:57:17.393249Z","steps":["trace[2077869998] 'read index received' (duration: 225.856917ms)","trace[2077869998] 'applied index is now lower than readState.Index' (duration: 91.511196ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:17.393356Z","caller":"traceutil/trace.go:171","msg":"trace[1285465832] transaction","detail":"{read_only:false; number_of_response:0; response_revision:325; }","duration":"320.120972ms","start":"2026-03-29T12:57:17.073192Z","end":"2026-03-29T12:57:17.393313Z","steps":["trace[1285465832] 'process raft request' (duration: 319.870838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:17.393625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.700318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-29T12:57:17.393735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:17.073177Z","time spent":"320.443736ms","remote":"127.0.0.1:38484","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:17.393784Z","caller":"traceutil/trace.go:171","msg":"trace[223686677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:325; }","duration":"317.88909ms","start":"2026-03-29T12:57:17.075863Z","end":"2026-03-29T12:57:17.393752Z","steps":["trace[223686677] 'agreement among raft nodes before linearized reading' (duration: 317.529745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:17.393898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:17.075856Z","time spent":"318.001102ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-29T12:57:17.428719Z","caller":"traceutil/trace.go:171","msg":"trace[1435210404] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"111.569764ms","start":"2026-03-29T12:57:17.317118Z","end":"2026-03-29T12:57:17.428688Z","steps":["trace[1435210404] 'process raft request' (duration: 111.479963ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:17.428829Z","caller":"traceutil/trace.go:171","msg":"trace[1359893051] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"122.663183ms","start":"2026-03-29T12:57:17.306113Z","end":"2026-03-29T12:57:17.428777Z","steps":["trace[1359893051] 'process raft request' (duration: 122.300848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:17.429289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.085713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-03-29T12:57:17.429377Z","caller":"traceutil/trace.go:171","msg":"trace[1023531756] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:327; }","duration":"126.245865ms","start":"2026-03-29T12:57:17.303111Z","end":"2026-03-29T12:57:17.429357Z","steps":["trace[1023531756] 'agreement among raft nodes before linearized reading' (duration: 126.075213ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:17.627525Z","caller":"traceutil/trace.go:171","msg":"trace[1119301480] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"182.714657ms","start":"2026-03-29T12:57:17.44477Z","end":"2026-03-29T12:57:17.627484Z","steps":["trace[1119301480] 'process raft request' (duration: 118.081068ms)","trace[1119301480] 'compare' (duration: 64.326725ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:17.627729Z","caller":"traceutil/trace.go:171","msg":"trace[474474191] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"181.854644ms","start":"2026-03-29T12:57:17.445834Z","end":"2026-03-29T12:57:17.627689Z","steps":["trace[474474191] 'process raft request' (duration: 181.55109ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:17.78092Z","caller":"traceutil/trace.go:171","msg":"trace[1365499382] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"139.776349ms","start":"2026-03-29T12:57:17.641106Z","end":"2026-03-29T12:57:17.780882Z","steps":["trace[1365499382] 'process raft request' (duration: 113.227108ms)","trace[1365499382] 'compare' (duration: 26.287928ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:17.980544Z","caller":"traceutil/trace.go:171","msg":"trace[416086376] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"109.23525ms","start":"2026-03-29T12:57:17.871282Z","end":"2026-03-29T12:57:17.980517Z","steps":["trace[416086376] 'process raft request' (duration: 109.145169ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:17.98102Z","caller":"traceutil/trace.go:171","msg":"trace[47500358] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"111.051137ms","start":"2026-03-29T12:57:17.869956Z","end":"2026-03-29T12:57:17.981007Z","steps":["trace[47500358] 'process raft request' (duration: 33.208588ms)","trace[47500358] 'compare' (duration: 77.019146ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:18.264822Z","caller":"traceutil/trace.go:171","msg":"trace[414600426] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"265.365744ms","start":"2026-03-29T12:57:17.999436Z","end":"2026-03-29T12:57:18.264801Z","steps":["trace[414600426] 'process raft request' (duration: 264.789256ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:18.270015Z","caller":"traceutil/trace.go:171","msg":"trace[981166999] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"267.85297ms","start":"2026-03-29T12:57:18.002141Z","end":"2026-03-29T12:57:18.269993Z","steps":["trace[981166999] 'process raft request' (duration: 267.364243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:19.289895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.369579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-03-29T12:57:19.289985Z","caller":"traceutil/trace.go:171","msg":"trace[1975100507] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:348; }","duration":"215.508481ms","start":"2026-03-29T12:57:19.074453Z","end":"2026-03-29T12:57:19.289961Z","steps":["trace[1975100507] 'range keys from in-memory index tree' (duration: 215.249087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:19.289992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.54991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-29T12:57:19.290164Z","caller":"traceutil/trace.go:171","msg":"trace[1164841875] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:348; }","duration":"224.839744ms","start":"2026-03-29T12:57:19.065246Z","end":"2026-03-29T12:57:19.290085Z","steps":["trace[1164841875] 'range keys from in-memory index tree' (duration: 224.432909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:19.290379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.037671ms","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-03-29T12:57:19.290478Z","caller":"traceutil/trace.go:171","msg":"trace[1457305514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:348; }","duration":"221.191873ms","start":"2026-03-29T12:57:19.069266Z","end":"2026-03-29T12:57:19.290458Z","steps":["trace[1457305514] 'range keys from in-memory index tree' (duration: 220.868548ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:19.560192Z","caller":"traceutil/trace.go:171","msg":"trace[761359579] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:361; }","duration":"249.421826ms","start":"2026-03-29T12:57:19.310738Z","end":"2026-03-29T12:57:19.56016Z","steps":["trace[761359579] 'read index received' (duration: 37.75677ms)","trace[761359579] 'applied index is now lower than readState.Index' (duration: 211.663576ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:19.560343Z","caller":"traceutil/trace.go:171","msg":"trace[758439623] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"255.266591ms","start":"2026-03-29T12:57:19.305063Z","end":"2026-03-29T12:57:19.56033Z","steps":["trace[758439623] 'process raft request' (duration: 195.0769ms)","trace[758439623] 'compare' (duration: 59.882876ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:19.560599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.222353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-29T12:57:19.560693Z","caller":"traceutil/trace.go:171","msg":"trace[984721414] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:350; }","duration":"228.358355ms","start":"2026-03-29T12:57:19.332313Z","end":"2026-03-29T12:57:19.560671Z","steps":["trace[984721414] 'agreement among raft nodes before linearized reading' (duration: 228.098321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:19.560797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.290557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-29T12:57:19.560948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.427123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-29T12:57:19.560979Z","caller":"traceutil/trace.go:171","msg":"trace[1436947897] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:350; }","duration":"190.477714ms","start":"2026-03-29T12:57:19.370491Z","end":"2026-03-29T12:57:19.560969Z","steps":["trace[1436947897] 'agreement among raft nodes before linearized reading' (duration: 190.430473ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:19.560979Z","caller":"traceutil/trace.go:171","msg":"trace[259307914] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:350; }","duration":"145.52184ms","start":"2026-03-29T12:57:19.415412Z","end":"2026-03-29T12:57:19.560934Z","steps":["trace[259307914] 'agreement among raft nodes before linearized reading' (duration: 145.153995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:19.561008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.950331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-29T12:57:19.561065Z","caller":"traceutil/trace.go:171","msg":"trace[1133120927] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:350; }","duration":"205.046622ms","start":"2026-03-29T12:57:19.356Z","end":"2026-03-29T12:57:19.561047Z","steps":["trace[1133120927] 'agreement among raft nodes before linearized reading' (duration: 204.949121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:19.56109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.321678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-29T12:57:19.561197Z","caller":"traceutil/trace.go:171","msg":"trace[1917135943] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:350; }","duration":"250.465772ms","start":"2026-03-29T12:57:19.310705Z","end":"2026-03-29T12:57:19.56117Z","steps":["trace[1917135943] 'agreement among raft nodes before linearized reading' (duration: 250.288899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:19.560899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.822829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-29T12:57:19.561269Z","caller":"traceutil/trace.go:171","msg":"trace[1393499491] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:350; }","duration":"182.214445ms","start":"2026-03-29T12:57:19.379044Z","end":"2026-03-29T12:57:19.561259Z","steps":["trace[1393499491] 'agreement among raft nodes before linearized reading' (duration: 181.82447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:19.561269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.511554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-29T12:57:19.561311Z","caller":"traceutil/trace.go:171","msg":"trace[1747963351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:350; }","duration":"118.568535ms","start":"2026-03-29T12:57:19.442731Z","end":"2026-03-29T12:57:19.5613Z","steps":["trace[1747963351] 'agreement among raft nodes before linearized reading' (duration: 118.487374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:19.561347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.456617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-29T12:57:19.561481Z","caller":"traceutil/trace.go:171","msg":"trace[1338853987] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:350; }","duration":"212.701961ms","start":"2026-03-29T12:57:19.348755Z","end":"2026-03-29T12:57:19.561457Z","steps":["trace[1338853987] 'agreement among raft nodes before linearized reading' (duration: 212.234315ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:20.185129Z","caller":"traceutil/trace.go:171","msg":"trace[1902734622] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"168.132207ms","start":"2026-03-29T12:57:20.016969Z","end":"2026-03-29T12:57:20.185101Z","steps":["trace[1902734622] 'read index received' (duration: 23.562476ms)","trace[1902734622] 'applied index is now lower than readState.Index' (duration: 144.568691ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:20.185279Z","caller":"traceutil/trace.go:171","msg":"trace[1027972222] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"169.315254ms","start":"2026-03-29T12:57:20.01591Z","end":"2026-03-29T12:57:20.185226Z","steps":["trace[1027972222] 'process raft request' (duration: 156.040725ms)","trace[1027972222] 'compare' (duration: 13.003736ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:20.18539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.37109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-03-29T12:57:20.185341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.912565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-29T12:57:20.185513Z","caller":"traceutil/trace.go:171","msg":"trace[551400664] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:375; }","duration":"168.579964ms","start":"2026-03-29T12:57:20.016909Z","end":"2026-03-29T12:57:20.185489Z","steps":["trace[551400664] 'agreement among raft nodes before linearized reading' (duration: 168.33867ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:20.185559Z","caller":"traceutil/trace.go:171","msg":"trace[1743105301] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:375; }","duration":"119.182279ms","start":"2026-03-29T12:57:20.066355Z","end":"2026-03-29T12:57:20.185538Z","steps":["trace[1743105301] 'agreement among raft nodes before linearized reading' (duration: 118.915506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:20.185796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.339199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-03-29T12:57:20.18584Z","caller":"traceutil/trace.go:171","msg":"trace[428735299] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:375; }","duration":"109.42685ms","start":"2026-03-29T12:57:20.076404Z","end":"2026-03-29T12:57:20.18583Z","steps":["trace[428735299] 'agreement among raft nodes before linearized reading' (duration: 109.297728ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:20.338585Z","caller":"traceutil/trace.go:171","msg":"trace[367799556] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"112.096379ms","start":"2026-03-29T12:57:20.226442Z","end":"2026-03-29T12:57:20.338538Z","steps":["trace[367799556] 'process raft request' (duration: 79.083827ms)","trace[367799556] 'compare' (duration: 32.758729ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:20.338689Z","caller":"traceutil/trace.go:171","msg":"trace[1306982688] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"106.823724ms","start":"2026-03-29T12:57:20.231813Z","end":"2026-03-29T12:57:20.338637Z","steps":["trace[1306982688] 'process raft request' (duration: 106.655871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:21.100859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.793706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T12:57:21.10101Z","caller":"traceutil/trace.go:171","msg":"trace[2141044700] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"348.263882ms","start":"2026-03-29T12:57:20.752722Z","end":"2026-03-29T12:57:21.100986Z","steps":["trace[2141044700] 'read index received' (duration: 52.75µs)","trace[2141044700] 'applied index is now lower than readState.Index' (duration: 348.209472ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:21.101074Z","caller":"traceutil/trace.go:171","msg":"trace[1370884473] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"718.31798ms","start":"2026-03-29T12:57:20.382707Z","end":"2026-03-29T12:57:21.101025Z","steps":["trace[1370884473] 'process raft request' (duration: 65.24438ms)","trace[1370884473] 'compare' (duration: 652.624764ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:21.101102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.385623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-29T12:57:21.10118Z","caller":"traceutil/trace.go:171","msg":"trace[1046743185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"348.503946ms","start":"2026-03-29T12:57:20.752659Z","end":"2026-03-29T12:57:21.101163Z","steps":["trace[1046743185] 'agreement among raft nodes before linearized reading' (duration: 348.387834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:21.10125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:20.752633Z","time spent":"348.603017ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-29T12:57:21.101293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:20.382685Z","time spent":"718.490802ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T12:57:21.320018Z","caller":"traceutil/trace.go:171","msg":"trace[1846219963] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"212.830946ms","start":"2026-03-29T12:57:21.107153Z","end":"2026-03-29T12:57:21.319984Z","steps":["trace[1846219963] 'process raft request' (duration: 212.565073ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:21.355036Z","caller":"traceutil/trace.go:171","msg":"trace[1012184031] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"244.788551ms","start":"2026-03-29T12:57:21.110222Z","end":"2026-03-29T12:57:21.35501Z","steps":["trace[1012184031] 'process raft request' (duration: 244.525438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:25.519537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.958267ms","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-03-29T12:57:25.519643Z","caller":"traceutil/trace.go:171","msg":"trace[229039304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:420; }","duration":"139.114309ms","start":"2026-03-29T12:57:25.380501Z","end":"2026-03-29T12:57:25.519616Z","steps":["trace[229039304] 'range keys from in-memory index tree' (duration: 138.837716ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:25.728917Z","caller":"traceutil/trace.go:171","msg":"trace[2043172525] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"105.097341ms","start":"2026-03-29T12:57:25.623771Z","end":"2026-03-29T12:57:25.728869Z","steps":["trace[2043172525] 'process raft request' (duration: 93.899243ms)","trace[2043172525] 'compare' (duration: 11.030186ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:27.382983Z","caller":"traceutil/trace.go:171","msg":"trace[2074627028] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"248.834392ms","start":"2026-03-29T12:57:27.134121Z","end":"2026-03-29T12:57:27.382955Z","steps":["trace[2074627028] 'read index received' (duration: 248.573408ms)","trace[2074627028] 'applied index is now lower than readState.Index' (duration: 259.964µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:27.383432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.280198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-29T12:57:27.383504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.694813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:27.383524Z","caller":"traceutil/trace.go:171","msg":"trace[146444871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"249.452961ms","start":"2026-03-29T12:57:27.134048Z","end":"2026-03-29T12:57:27.383501Z","steps":["trace[146444871] 'agreement among raft nodes before linearized reading' (duration: 249.219447ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:27.38355Z","caller":"traceutil/trace.go:171","msg":"trace[1312492893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"244.755935ms","start":"2026-03-29T12:57:27.13878Z","end":"2026-03-29T12:57:27.383536Z","steps":["trace[1312492893] 'agreement among raft nodes before linearized reading' (duration: 244.671203ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:27.383684Z","caller":"traceutil/trace.go:171","msg":"trace[74691927] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"704.277781ms","start":"2026-03-29T12:57:26.679366Z","end":"2026-03-29T12:57:27.383643Z","steps":["trace[74691927] 'process raft request' (duration: 703.420979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:27.383883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:26.679329Z","time spent":"704.446503ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T12:57:28.047386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237088569090994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T12:57:28.548263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237088569090994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T12:57:28.77734Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.385501702s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-29T12:57:28.915546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.783198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T12:57:28.915714Z","caller":"traceutil/trace.go:171","msg":"trace[1428384531] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"1.523925161s","start":"2026-03-29T12:57:27.391757Z","end":"2026-03-29T12:57:28.915683Z","steps":["trace[1428384531] 'process raft request' (duration: 1.385843619s)","trace[1428384531] 'compare' (duration: 137.545065ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:28.915804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:27.391741Z","time spent":"1.524017683s","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T12:57:30.15303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080721051s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T12:57:30.153635Z","caller":"traceutil/trace.go:171","msg":"trace[1593595170] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:438; }","duration":"2.607287352s","start":"2026-03-29T12:57:27.546298Z","end":"2026-03-29T12:57:30.153585Z","steps":["trace[1593595170] 'read index received' (duration: 1.23131163s)","trace[1593595170] 'applied index is now lower than readState.Index' (duration: 1.375964722s)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:30.153735Z","caller":"traceutil/trace.go:171","msg":"trace[718184528] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"2.761778109s","start":"2026-03-29T12:57:27.391877Z","end":"2026-03-29T12:57:30.153655Z","steps":["trace[718184528] 'process raft request' (duration: 1.680308588s)","trace[718184528] 'compare' (duration: 1.080522129s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:30.154066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:27.391845Z","time spent":"2.762075764s","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T12:57:30.157482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.423781069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T12:57:30.157542Z","caller":"traceutil/trace.go:171","msg":"trace[33675466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:425; }","duration":"2.423894469s","start":"2026-03-29T12:57:27.733634Z","end":"2026-03-29T12:57:30.157528Z","steps":["trace[33675466] 'agreement among raft nodes before linearized reading' (duration: 2.423718397s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:30.157578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:27.733606Z","time spent":"2.423964301s","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T12:57:30.157782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016412672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T12:57:30.157773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.61145344s","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-03-29T12:57:30.157831Z","caller":"traceutil/trace.go:171","msg":"trace[1617598872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"1.016517764s","start":"2026-03-29T12:57:29.141297Z","end":"2026-03-29T12:57:30.157815Z","steps":["trace[1617598872] 'agreement among raft nodes before linearized reading' (duration: 1.016435212s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:30.157901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:29.141266Z","time spent":"1.016625975s","remote":"127.0.0.1:38292","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-03-29T12:57:30.157894Z","caller":"traceutil/trace.go:171","msg":"trace[949078253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"2.611570452s","start":"2026-03-29T12:57:27.546273Z","end":"2026-03-29T12:57:30.157843Z","steps":["trace[949078253] 'agreement among raft nodes before linearized reading' (duration: 2.607487585s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:30.157961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:27.546212Z","time spent":"2.611737303s","remote":"127.0.0.1:38556","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-03-29T12:57:35.191449Z","caller":"traceutil/trace.go:171","msg":"trace[1406987376] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"247.283749ms","start":"2026-03-29T12:57:34.944113Z","end":"2026-03-29T12:57:35.191397Z","steps":["trace[1406987376] 'process raft request' (duration: 247.066676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:48.641361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237088569091210,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T12:57:49.142169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237088569091210,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T12:57:49.219478Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096759793s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T12:57:49.219779Z","caller":"traceutil/trace.go:171","msg":"trace[554413820] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.097213901s","start":"2026-03-29T12:57:48.12254Z","end":"2026-03-29T12:57:49.219754Z","steps":["trace[554413820] 'process raft request' (duration: 1.097095496s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.2199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.122509Z","time spent":"1.097325915s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:49.228439Z","caller":"traceutil/trace.go:171","msg":"trace[1918333406] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.089782911s","start":"2026-03-29T12:57:48.138642Z","end":"2026-03-29T12:57:49.228425Z","steps":["trace[1918333406] 'process raft request' (duration: 1.089430027s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.228571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.138625Z","time spent":"1.089866254s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:49.228819Z","caller":"traceutil/trace.go:171","msg":"trace[1374942330] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.097252249s","start":"2026-03-29T12:57:48.131555Z","end":"2026-03-29T12:57:49.228807Z","steps":["trace[1374942330] 'process raft request' (duration: 1.096480718s)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:49.228809Z","caller":"traceutil/trace.go:171","msg":"trace[1132338529] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.105333903s","start":"2026-03-29T12:57:48.123395Z","end":"2026-03-29T12:57:49.228729Z","steps":["trace[1132338529] 'process raft request' (duration: 1.10453411s)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:49.228852Z","caller":"traceutil/trace.go:171","msg":"trace[2141232828] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.055259724s","start":"2026-03-29T12:57:48.173558Z","end":"2026-03-29T12:57:49.228817Z","steps":["trace[2141232828] 'process raft request' (duration: 1.054748373s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.228904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.131531Z","time spent":"1.097319041s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:49.228893Z","caller":"traceutil/trace.go:171","msg":"trace[1322458426] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.105361804s","start":"2026-03-29T12:57:48.123447Z","end":"2026-03-29T12:57:49.228809Z","steps":["trace[1322458426] 'process raft request' (duration: 1.104552471s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.229002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.173527Z","time spent":"1.055406561s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:49.229058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.123382Z","time spent":"1.105559861s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:49.229127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.123433Z","time spent":"1.105584874s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:49.229174Z","caller":"traceutil/trace.go:171","msg":"trace[803981934] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"918.372413ms","start":"2026-03-29T12:57:48.310791Z","end":"2026-03-29T12:57:49.229163Z","steps":["trace[803981934] 'process raft request' (duration: 917.703066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.229264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.310771Z","time spent":"918.466297ms","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:49.229317Z","caller":"traceutil/trace.go:171","msg":"trace[1722138187] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.037669589s","start":"2026-03-29T12:57:48.191141Z","end":"2026-03-29T12:57:49.22881Z","steps":["trace[1722138187] 'process raft request' (duration: 1.037213981s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.229377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.191122Z","time spent":"1.038228351s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:49.229401Z","caller":"traceutil/trace.go:171","msg":"trace[1148461617] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.088766488s","start":"2026-03-29T12:57:48.14062Z","end":"2026-03-29T12:57:49.229387Z","steps":["trace[1148461617] 'process raft request' (duration: 1.087525398s)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:49.229411Z","caller":"traceutil/trace.go:171","msg":"trace[1369507495] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.08324491s","start":"2026-03-29T12:57:48.146129Z","end":"2026-03-29T12:57:49.229374Z","steps":["trace[1369507495] 'process raft request' (duration: 1.082049011s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.229461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.140608Z","time spent":"1.088821141s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:49.229486Z","caller":"traceutil/trace.go:171","msg":"trace[1713375764] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.07912996s","start":"2026-03-29T12:57:48.150345Z","end":"2026-03-29T12:57:49.229475Z","steps":["trace[1713375764] 'process raft request' (duration: 1.077866828s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.229499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.146112Z","time spent":"1.083335064s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T12:57:49.229534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.150313Z","time spent":"1.079198612s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:49.229975Z","caller":"traceutil/trace.go:171","msg":"trace[1798344364] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.063386321s","start":"2026-03-29T12:57:48.166337Z","end":"2026-03-29T12:57:49.229723Z","steps":["trace[1798344364] 'process raft request' (duration: 1.061910641s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.230079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.166319Z","time spent":"1.063714394s","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:49.238458Z","caller":"traceutil/trace.go:171","msg":"trace[1587465596] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:553; }","duration":"1.098040458s","start":"2026-03-29T12:57:48.140378Z","end":"2026-03-29T12:57:49.238419Z","steps":["trace[1587465596] 'read index received' (duration: 1.079394975s)","trace[1587465596] 'applied index is now lower than readState.Index' (duration: 18.366932ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:49.241106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100711818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:49.241159Z","caller":"traceutil/trace.go:171","msg":"trace[326943021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"1.100806991s","start":"2026-03-29T12:57:48.140332Z","end":"2026-03-29T12:57:49.241139Z","steps":["trace[326943021] 'agreement among raft nodes before linearized reading' (duration: 1.098414543s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.241184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.140311Z","time spent":"1.100868253s","remote":"127.0.0.1:38292","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-03-29T12:57:49.259103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.997429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-29T12:57:49.259198Z","caller":"traceutil/trace.go:171","msg":"trace[1457518954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"990.122715ms","start":"2026-03-29T12:57:48.269049Z","end":"2026-03-29T12:57:49.259172Z","steps":["trace[1457518954] 'agreement among raft nodes before linearized reading' (duration: 989.867164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.259236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.269042Z","time spent":"990.185627ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T12:57:49.259513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.29335ms","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-03-29T12:57:49.259586Z","caller":"traceutil/trace.go:171","msg":"trace[1069834934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"990.416236ms","start":"2026-03-29T12:57:48.269155Z","end":"2026-03-29T12:57:49.259571Z","steps":["trace[1069834934] 'agreement among raft nodes before linearized reading' (duration: 990.312412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.259621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.269122Z","time spent":"990.493429ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-29T12:57:49.262362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.069384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-29T12:57:49.262407Z","caller":"traceutil/trace.go:171","msg":"trace[888452019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"994.328397ms","start":"2026-03-29T12:57:48.268064Z","end":"2026-03-29T12:57:49.262393Z","steps":["trace[888452019] 'agreement among raft nodes before linearized reading' (duration: 990.895146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:49.262485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:48.26805Z","time spent":"994.42624ms","remote":"127.0.0.1:38556","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-03-29T12:57:50.119985Z","caller":"traceutil/trace.go:171","msg":"trace[1475572442] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"277.490386ms","start":"2026-03-29T12:57:49.842474Z","end":"2026-03-29T12:57:50.119965Z","steps":["trace[1475572442] 'read index received' (duration: 277.287737ms)","trace[1475572442] 'applied index is now lower than readState.Index' (duration: 201.699µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:50.12007Z","caller":"traceutil/trace.go:171","msg":"trace[1110434129] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"277.696004ms","start":"2026-03-29T12:57:49.842365Z","end":"2026-03-29T12:57:50.120061Z","steps":["trace[1110434129] 'process raft request' (duration: 277.346609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.120269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.765106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.120333Z","caller":"traceutil/trace.go:171","msg":"trace[303102452] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:591; }","duration":"277.859619ms","start":"2026-03-29T12:57:49.842453Z","end":"2026-03-29T12:57:50.120312Z","steps":["trace[303102452] 'agreement among raft nodes before linearized reading' (duration: 277.662071ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:50.153789Z","caller":"traceutil/trace.go:171","msg":"trace[1556893877] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"138.381035ms","start":"2026-03-29T12:57:50.015381Z","end":"2026-03-29T12:57:50.153762Z","steps":["trace[1556893877] 'process raft request' (duration: 125.261454ms)","trace[1556893877] 'compare' (duration: 11.671653ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:50.153981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.666969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T12:57:50.153956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.45995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.154046Z","caller":"traceutil/trace.go:171","msg":"trace[1190001672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:593; }","duration":"200.750423ms","start":"2026-03-29T12:57:49.953269Z","end":"2026-03-29T12:57:50.15402Z","steps":["trace[1190001672] 'agreement among raft nodes before linearized reading' (duration: 200.643479ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:50.154084Z","caller":"traceutil/trace.go:171","msg":"trace[913760836] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:593; }","duration":"139.898078ms","start":"2026-03-29T12:57:50.014157Z","end":"2026-03-29T12:57:50.154055Z","steps":["trace[913760836] 'agreement among raft nodes before linearized reading' (duration: 139.423309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.154109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.817471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T12:57:50.154259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.112656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.154294Z","caller":"traceutil/trace.go:171","msg":"trace[1983190438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:593; }","duration":"140.173788ms","start":"2026-03-29T12:57:50.014113Z","end":"2026-03-29T12:57:50.154287Z","steps":["trace[1983190438] 'agreement among raft nodes before linearized reading' (duration: 140.126587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.154312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.161811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.154413Z","caller":"traceutil/trace.go:171","msg":"trace[116477644] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:593; }","duration":"239.103361ms","start":"2026-03-29T12:57:49.915173Z","end":"2026-03-29T12:57:50.154276Z","steps":["trace[116477644] 'agreement among raft nodes before linearized reading' (duration: 238.773278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.154467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.967251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.154501Z","caller":"traceutil/trace.go:171","msg":"trace[164371358] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:593; }","duration":"201.051565ms","start":"2026-03-29T12:57:49.953442Z","end":"2026-03-29T12:57:50.154493Z","steps":["trace[164371358] 'agreement among raft nodes before linearized reading' (duration: 200.956721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.154487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.033517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.154544Z","caller":"traceutil/trace.go:171","msg":"trace[200954916] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:593; }","duration":"127.11708ms","start":"2026-03-29T12:57:50.027419Z","end":"2026-03-29T12:57:50.154536Z","steps":["trace[200954916] 'agreement among raft nodes before linearized reading' (duration: 127.025466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.154811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.621298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.154852Z","caller":"traceutil/trace.go:171","msg":"trace[1266228587] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:593; }","duration":"140.663579ms","start":"2026-03-29T12:57:50.014179Z","end":"2026-03-29T12:57:50.154843Z","steps":["trace[1266228587] 'agreement among raft nodes before linearized reading' (duration: 140.601197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.154234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.088021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.154976Z","caller":"traceutil/trace.go:171","msg":"trace[1205030759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:593; }","duration":"239.847892ms","start":"2026-03-29T12:57:49.915116Z","end":"2026-03-29T12:57:50.154964Z","steps":["trace[1205030759] 'agreement among raft nodes before linearized reading' (duration: 239.058949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.155109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.205463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T12:57:50.15512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.935561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.154364Z","caller":"traceutil/trace.go:171","msg":"trace[1812026429] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:593; }","duration":"127.236815ms","start":"2026-03-29T12:57:50.027112Z","end":"2026-03-29T12:57:50.154349Z","steps":["trace[1812026429] 'agreement among raft nodes before linearized reading' (duration: 127.148142ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:50.155172Z","caller":"traceutil/trace.go:171","msg":"trace[261076468] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:593; }","duration":"202.001373ms","start":"2026-03-29T12:57:49.953143Z","end":"2026-03-29T12:57:50.155145Z","steps":["trace[261076468] 'agreement among raft nodes before linearized reading' (duration: 201.899909ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:50.155399Z","caller":"traceutil/trace.go:171","msg":"trace[1497110080] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:593; }","duration":"127.240135ms","start":"2026-03-29T12:57:50.027888Z","end":"2026-03-29T12:57:50.155128Z","steps":["trace[1497110080] 'agreement among raft nodes before linearized reading' (duration: 126.000205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.152546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.813195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:50.163313Z","caller":"traceutil/trace.go:171","msg":"trace[1225632042] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:593; }","duration":"320.600512ms","start":"2026-03-29T12:57:49.842693Z","end":"2026-03-29T12:57:50.163293Z","steps":["trace[1225632042] 'agreement among raft nodes before linearized reading' (duration: 309.738072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:50.163381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:49.842657Z","time spent":"320.702696ms","remote":"127.0.0.1:43982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"info","ts":"2026-03-29T12:57:50.153849Z","caller":"traceutil/trace.go:171","msg":"trace[1376955572] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"193.864592ms","start":"2026-03-29T12:57:49.959892Z","end":"2026-03-29T12:57:50.153757Z","steps":["trace[1376955572] 'process raft request' (duration: 176.116564ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:55.21589Z","caller":"traceutil/trace.go:171","msg":"trace[1846405420] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"168.336682ms","start":"2026-03-29T12:57:55.047521Z","end":"2026-03-29T12:57:55.215858Z","steps":["trace[1846405420] 'process raft request' (duration: 166.180629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:55.799122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237088569091408,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T12:57:56.299914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237088569091408,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T12:57:56.774412Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.536718712s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T12:57:56.793713Z","caller":"traceutil/trace.go:171","msg":"trace[1853307501] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.556075424s","start":"2026-03-29T12:57:55.237581Z","end":"2026-03-29T12:57:56.793656Z","steps":["trace[1853307501] 'process raft request' (duration: 1.537214491s)","trace[1853307501] 'compare' (duration: 18.344904ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:56.794201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:55.23756Z","time spent":"1.556260983s","remote":"127.0.0.1:38484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T12:57:57.779708Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.005074337s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T12:57:57.823037Z","caller":"traceutil/trace.go:171","msg":"trace[1338633960] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.548611994s","start":"2026-03-29T12:57:55.274388Z","end":"2026-03-29T12:57:57.823Z","steps":["trace[1338633960] 'process raft request' (duration: 2.505499636s)","trace[1338633960] 'compare' (duration: 42.681701ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:57.82306Z","caller":"traceutil/trace.go:171","msg":"trace[919619025] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.096844725s","start":"2026-03-29T12:57:55.726164Z","end":"2026-03-29T12:57:57.823008Z","steps":["trace[919619025] 'process raft request' (duration: 2.096731861s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:57.823181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:55.274358Z","time spent":"2.548751699s","remote":"127.0.0.1:38620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T12:57:57.823039Z","caller":"traceutil/trace.go:171","msg":"trace[1609051046] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:665; }","duration":"2.524340763s","start":"2026-03-29T12:57:55.29868Z","end":"2026-03-29T12:57:57.823021Z","steps":["trace[1609051046] 'read index received' (duration: 1.476026783s)","trace[1609051046] 'applied index is now lower than readState.Index' (duration: 1.04831296s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:57:57.823318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:55.726113Z","time spent":"2.097067784s","remote":"127.0.0.1:38556","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-03-29T12:57:57.823579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.168001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T12:57:57.82358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.516126175s","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-03-29T12:57:57.823622Z","caller":"traceutil/trace.go:171","msg":"trace[945857589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"686.260204ms","start":"2026-03-29T12:57:57.137347Z","end":"2026-03-29T12:57:57.823607Z","steps":["trace[945857589] 'agreement among raft nodes before linearized reading' (duration: 686.190471ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:57.823639Z","caller":"traceutil/trace.go:171","msg":"trace[1223453543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"2.516230209s","start":"2026-03-29T12:57:55.307391Z","end":"2026-03-29T12:57:57.823621Z","steps":["trace[1223453543] 'agreement among raft nodes before linearized reading' (duration: 2.516032442s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:57.823636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.524953257s","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-03-29T12:57:57.823689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:55.307355Z","time spent":"2.516323544s","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-29T12:57:57.823694Z","caller":"traceutil/trace.go:171","msg":"trace[2102348183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"2.525048881s","start":"2026-03-29T12:57:55.29863Z","end":"2026-03-29T12:57:57.823679Z","steps":["trace[2102348183] 'agreement among raft nodes before linearized reading' (duration: 2.524454808s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:57.823731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:55.298611Z","time spent":"2.525111744s","remote":"127.0.0.1:38556","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":"warn","ts":"2026-03-29T12:57:57.823656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:57.137326Z","time spent":"686.323156ms","remote":"127.0.0.1:38292","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-03-29T12:57:57.823862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.508646436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-29T12:57:57.823895Z","caller":"traceutil/trace.go:171","msg":"trace[595529386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"2.50873362s","start":"2026-03-29T12:57:55.315151Z","end":"2026-03-29T12:57:57.823884Z","steps":["trace[595529386] 'agreement among raft nodes before linearized reading' (duration: 2.508645936s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:57.823921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:55.315118Z","time spent":"2.508797552s","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-29T12:57:57.824236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022944188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qs9j7\" ","response":"range_response_count:1 size:17149"} {"level":"warn","ts":"2026-03-29T12:57:57.824247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.504984715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T12:57:57.824286Z","caller":"traceutil/trace.go:171","msg":"trace[84416367] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qs9j7; range_end:; response_count:1; response_revision:640; }","duration":"1.023054401s","start":"2026-03-29T12:57:56.801214Z","end":"2026-03-29T12:57:57.824269Z","steps":["trace[84416367] 'agreement among raft nodes before linearized reading' (duration: 1.022905946s)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:57.824303Z","caller":"traceutil/trace.go:171","msg":"trace[507320356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"2.505066078s","start":"2026-03-29T12:57:55.319222Z","end":"2026-03-29T12:57:57.824288Z","steps":["trace[507320356] 'agreement among raft nodes before linearized reading' (duration: 2.504539567s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:57.824324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:56.80119Z","time spent":"1.023126464s","remote":"127.0.0.1:38484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17173,"request content":"key:\"/registry/pods/kube-system/cilium-qs9j7\" "} {"level":"warn","ts":"2026-03-29T12:57:57.824339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:55.319211Z","time spent":"2.5051211s","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-29T12:57:58.047725Z","caller":"traceutil/trace.go:171","msg":"trace[311583909] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"216.274464ms","start":"2026-03-29T12:57:57.831423Z","end":"2026-03-29T12:57:58.047697Z","steps":["trace[311583909] 'process raft request' (duration: 195.456329ms)","trace[311583909] 'compare' (duration: 20.646348ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:58.048185Z","caller":"traceutil/trace.go:171","msg":"trace[54900568] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"214.568268ms","start":"2026-03-29T12:57:57.833602Z","end":"2026-03-29T12:57:58.04817Z","steps":["trace[54900568] 'process raft request' (duration: 214.046629ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:57:58.04821Z","caller":"traceutil/trace.go:171","msg":"trace[1491198656] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:669; }","duration":"213.983767ms","start":"2026-03-29T12:57:57.834202Z","end":"2026-03-29T12:57:58.048186Z","steps":["trace[1491198656] 'read index received' (duration: 3.438611ms)","trace[1491198656] 'applied index is now lower than readState.Index' (duration: 210.543556ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T12:57:58.048503Z","caller":"traceutil/trace.go:171","msg":"trace[325893540] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"213.364743ms","start":"2026-03-29T12:57:57.8351Z","end":"2026-03-29T12:57:58.048464Z","steps":["trace[325893540] 'process raft request' (duration: 212.914266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:58.048548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.296709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T12:57:58.048848Z","caller":"traceutil/trace.go:171","msg":"trace[1081836143] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:644; }","duration":"214.662092ms","start":"2026-03-29T12:57:57.834165Z","end":"2026-03-29T12:57:58.048827Z","steps":["trace[1081836143] 'agreement among raft nodes before linearized reading' (duration: 214.297499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:57:59.638503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237088569091420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T12:58:00.139673Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237088569091420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T12:58:00.640393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237088569091420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T12:58:00.995902Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.57123116s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T12:58:00.997306Z","caller":"traceutil/trace.go:171","msg":"trace[1877549288] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"2.573627849s","start":"2026-03-29T12:57:58.423647Z","end":"2026-03-29T12:58:00.997275Z","steps":["trace[1877549288] 'process raft request' (duration: 2.572859521s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:58:00.997467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:58.423623Z","time spent":"2.573740534s","remote":"127.0.0.1:38398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T12:58:01.031148Z","caller":"traceutil/trace.go:171","msg":"trace[13341946] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"1.893406347s","start":"2026-03-29T12:57:59.137716Z","end":"2026-03-29T12:58:01.031123Z","steps":["trace[13341946] 'read index received' (duration: 1.85841562s)","trace[13341946] 'applied index is now lower than readState.Index' (duration: 34.989357ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:58:01.031314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.893581493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T12:58:01.031388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189515424s","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-03-29T12:58:01.031398Z","caller":"traceutil/trace.go:171","msg":"trace[466382949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"1.893720959s","start":"2026-03-29T12:57:59.137659Z","end":"2026-03-29T12:58:01.03138Z","steps":["trace[466382949] 'agreement among raft nodes before linearized reading' (duration: 1.893575954s)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:58:01.031424Z","caller":"traceutil/trace.go:171","msg":"trace[237481026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"1.189559757s","start":"2026-03-29T12:57:59.841845Z","end":"2026-03-29T12:58:01.031405Z","steps":["trace[237481026] 'agreement among raft nodes before linearized reading' (duration: 1.189412961s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:58:01.031451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:59.841766Z","time spent":"1.189678921s","remote":"127.0.0.1:38556","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":"warn","ts":"2026-03-29T12:58:01.031448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:57:59.137638Z","time spent":"1.893802642s","remote":"127.0.0.1:38292","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-03-29T12:58:01.031614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.769894ms","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-03-29T12:58:01.031642Z","caller":"traceutil/trace.go:171","msg":"trace[923173580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"978.836236ms","start":"2026-03-29T12:58:00.052798Z","end":"2026-03-29T12:58:01.031635Z","steps":["trace[923173580] 'agreement among raft nodes before linearized reading' (duration: 978.762613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:58:01.031659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:58:00.052778Z","time spent":"978.877057ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-29T12:58:01.03163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.724075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T12:58:01.031833Z","caller":"traceutil/trace.go:171","msg":"trace[1705165051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"977.976714ms","start":"2026-03-29T12:58:00.053815Z","end":"2026-03-29T12:58:01.031791Z","steps":["trace[1705165051] 'agreement among raft nodes before linearized reading' (duration: 977.606821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:58:01.032022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:58:00.053779Z","time spent":"978.228833ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T12:58:01.032968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.581755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-29T12:58:01.033088Z","caller":"traceutil/trace.go:171","msg":"trace[306143894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"979.253571ms","start":"2026-03-29T12:58:00.053816Z","end":"2026-03-29T12:58:01.03307Z","steps":["trace[306143894] 'agreement among raft nodes before linearized reading' (duration: 977.714675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:58:01.033247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:58:00.053805Z","time spent":"979.432158ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-29T12:58:01.615723Z","caller":"traceutil/trace.go:171","msg":"trace[1898412852] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"122.70848ms","start":"2026-03-29T12:58:01.492989Z","end":"2026-03-29T12:58:01.615697Z","steps":["trace[1898412852] 'process raft request' (duration: 121.966483ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:58:14.04476Z","caller":"traceutil/trace.go:171","msg":"trace[32771447] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"135.568538ms","start":"2026-03-29T12:58:13.909165Z","end":"2026-03-29T12:58:14.044734Z","steps":["trace[32771447] 'read index received' (duration: 135.451195ms)","trace[32771447] 'applied index is now lower than readState.Index' (duration: 115.663µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:58:14.044923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.741723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T12:58:14.044957Z","caller":"traceutil/trace.go:171","msg":"trace[1171439154] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:793; }","duration":"135.798185ms","start":"2026-03-29T12:58:13.909148Z","end":"2026-03-29T12:58:14.044946Z","steps":["trace[1171439154] 'agreement among raft nodes before linearized reading' (duration: 135.698312ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:58:15.251532Z","caller":"traceutil/trace.go:171","msg":"trace[1234958286] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"128.099102ms","start":"2026-03-29T12:58:15.123408Z","end":"2026-03-29T12:58:15.251508Z","steps":["trace[1234958286] 'process raft request' (duration: 127.917177ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:58:15.254404Z","caller":"traceutil/trace.go:171","msg":"trace[2082748619] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"129.365754ms","start":"2026-03-29T12:58:15.125015Z","end":"2026-03-29T12:58:15.254381Z","steps":["trace[2082748619] 'process raft request' (duration: 128.833277ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:58:15.254627Z","caller":"traceutil/trace.go:171","msg":"trace[2115634150] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"129.476098ms","start":"2026-03-29T12:58:15.125128Z","end":"2026-03-29T12:58:15.254605Z","steps":["trace[2115634150] 'process raft request' (duration: 128.838487ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:58:15.254817Z","caller":"traceutil/trace.go:171","msg":"trace[221363768] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"129.082956ms","start":"2026-03-29T12:58:15.125707Z","end":"2026-03-29T12:58:15.25479Z","steps":["trace[221363768] 'process raft request' (duration: 128.298851ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:58:17.036935Z","caller":"traceutil/trace.go:171","msg":"trace[64486602] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"728.599951ms","start":"2026-03-29T12:58:16.308312Z","end":"2026-03-29T12:58:17.036912Z","steps":["trace[64486602] 'process raft request' (duration: 728.453576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:58:17.037101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T12:58:16.308284Z","time spent":"728.727125ms","remote":"127.0.0.1:38484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T12:58:39.637068Z","caller":"traceutil/trace.go:171","msg":"trace[1049952323] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"247.949575ms","start":"2026-03-29T12:58:39.388956Z","end":"2026-03-29T12:58:39.636906Z","steps":["trace[1049952323] 'process raft request' (duration: 247.622276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:58:39.637145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.521287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-29T12:58:39.637221Z","caller":"traceutil/trace.go:171","msg":"trace[1029170628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"246.677691ms","start":"2026-03-29T12:58:39.390529Z","end":"2026-03-29T12:58:39.637206Z","steps":["trace[1029170628] 'agreement among raft nodes before linearized reading' (duration: 246.420924ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T12:58:39.636848Z","caller":"traceutil/trace.go:171","msg":"trace[1811334671] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"246.22405ms","start":"2026-03-29T12:58:39.390591Z","end":"2026-03-29T12:58:39.636815Z","steps":["trace[1811334671] 'read index received' (duration: 245.928971ms)","trace[1811334671] 'applied index is now lower than readState.Index' (duration: 293.779µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T12:58:39.637828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.98706ms","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-03-29T12:58:39.637954Z","caller":"traceutil/trace.go:171","msg":"trace[1575895998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"247.156444ms","start":"2026-03-29T12:58:39.390779Z","end":"2026-03-29T12:58:39.637935Z","steps":["trace[1575895998] 'agreement among raft nodes before linearized reading' (duration: 246.953178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:58:39.638175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.213863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-29T12:58:39.638283Z","caller":"traceutil/trace.go:171","msg":"trace[778486762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"244.43815ms","start":"2026-03-29T12:58:39.393822Z","end":"2026-03-29T12:58:39.638261Z","steps":["trace[778486762] 'agreement among raft nodes before linearized reading' (duration: 243.919415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T12:58:39.63848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.781652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-29T12:58:39.638613Z","caller":"traceutil/trace.go:171","msg":"trace[140414386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"247.918315ms","start":"2026-03-29T12:58:39.390676Z","end":"2026-03-29T12:58:39.638594Z","steps":["trace[140414386] 'agreement among raft nodes before linearized reading' (duration: 246.921968ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T13:00:09.25683Z","caller":"traceutil/trace.go:171","msg":"trace[1704158598] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1401; }","duration":"118.138883ms","start":"2026-03-29T13:00:09.138656Z","end":"2026-03-29T13:00:09.256795Z","steps":["trace[1704158598] 'read index received' (duration: 115.062034ms)","trace[1704158598] 'applied index is now lower than readState.Index' (duration: 3.074389ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T13:00:09.257064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.381927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T13:00:09.257189Z","caller":"traceutil/trace.go:171","msg":"trace[1276410830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1339; }","duration":"118.587792ms","start":"2026-03-29T13:00:09.138576Z","end":"2026-03-29T13:00:09.257164Z","steps":["trace[1276410830] 'agreement among raft nodes before linearized reading' (duration: 118.363818ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T13:00:14.836719Z","caller":"traceutil/trace.go:171","msg":"trace[886296970] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"404.659515ms","start":"2026-03-29T13:00:14.432025Z","end":"2026-03-29T13:00:14.836685Z","steps":["trace[886296970] 'process raft request' (duration: 404.472792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T13:00:14.836976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T13:00:14.431988Z","time spent":"404.90205ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}