{"level":"warn","ts":"2026-03-31T10:57:40.303451Z","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-31T10:57:40.303544Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-31T10:57:40.303612Z","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-31T10:57:40.30362Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-03-31T10:57:40.303637Z","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-31T10:57:40.30429Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-03-31T10:57:40.304366Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-31T10:57:40.312627Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.974654ms"} {"level":"info","ts":"2026-03-31T10:57:40.322679Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-03-31T10:57:40.322785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T10:57:40.322807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-03-31T10:57:40.322818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T10:57:40.322831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-03-31T10:57:40.322871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-03-31T10:57:40.354187Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T10:57:40.358036Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T10:57:40.359935Z","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-31T10:57:40.362481Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T10:57:40.362828Z","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-31T10:57:40.362947Z","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-31T10:57:40.362958Z","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-31T10:57:40.363021Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T10:57:40.365741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-03-31T10:57:40.366582Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-03-31T10:57:40.368042Z","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-31T10:57:40.368363Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T10:57:40.368413Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T10:57:40.368601Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-03-31T10:57:40.368646Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-03-31T10:57:40.524375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T10:57:40.524445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T10:57:40.524471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-03-31T10:57:40.524492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-03-31T10:57:40.524504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-03-31T10:57:40.524521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-03-31T10:57:40.52455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-03-31T10:57:40.525924Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T10:57:40.525983Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T10:57:40.526079Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T10:57:40.526095Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:57:40.526401Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T10:57:40.52644Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T10:57:40.527392Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:57:40.527531Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:57:40.527575Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:57:40.528353Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T10:57:40.529581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-03-31T10:57:54.863885Z","caller":"traceutil/trace.go:171","msg":"trace[1064643026] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"279.932384ms","start":"2026-03-31T10:57:54.583927Z","end":"2026-03-31T10:57:54.863859Z","steps":["trace[1064643026] 'read index received' (duration: 279.755469ms)","trace[1064643026] 'applied index is now lower than readState.Index' (duration: 175.675µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:57:54.863956Z","caller":"traceutil/trace.go:171","msg":"trace[317257033] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"339.368832ms","start":"2026-03-31T10:57:54.524546Z","end":"2026-03-31T10:57:54.863914Z","steps":["trace[317257033] 'process raft request' (duration: 339.143816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:57:54.86408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.099388ms","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-31T10:57:54.864152Z","caller":"traceutil/trace.go:171","msg":"trace[500671562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:362; }","duration":"280.278283ms","start":"2026-03-31T10:57:54.58386Z","end":"2026-03-31T10:57:54.864139Z","steps":["trace[500671562] 'agreement among raft nodes before linearized reading' (duration: 280.120599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:57:54.864651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:57:54.524523Z","time spent":"339.520796ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:57:55.53877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.624092ms","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-31T10:57:55.538863Z","caller":"traceutil/trace.go:171","msg":"trace[1426382109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:363; }","duration":"314.760175ms","start":"2026-03-31T10:57:55.224082Z","end":"2026-03-31T10:57:55.538842Z","steps":["trace[1426382109] 'range keys from in-memory index tree' (duration: 314.514409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:57:55.538906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:57:55.224065Z","time spent":"314.832199ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T10:57:57.816399Z","caller":"traceutil/trace.go:171","msg":"trace[1812267707] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"260.378048ms","start":"2026-03-31T10:57:57.555982Z","end":"2026-03-31T10:57:57.81636Z","steps":["trace[1812267707] 'process raft request' (duration: 260.161782ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:57:58.371962Z","caller":"traceutil/trace.go:171","msg":"trace[473076088] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"279.176592ms","start":"2026-03-31T10:57:58.09275Z","end":"2026-03-31T10:57:58.371926Z","steps":["trace[473076088] 'process raft request' (duration: 278.977886ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:57:58.520579Z","caller":"traceutil/trace.go:171","msg":"trace[840711115] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"134.285688ms","start":"2026-03-31T10:57:58.386257Z","end":"2026-03-31T10:57:58.520542Z","steps":["trace[840711115] 'process raft request' (duration: 134.135793ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:57:59.027273Z","caller":"traceutil/trace.go:171","msg":"trace[1634207332] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"551.577547ms","start":"2026-03-31T10:57:58.475665Z","end":"2026-03-31T10:57:59.027243Z","steps":["trace[1634207332] 'process raft request' (duration: 518.988016ms)","trace[1634207332] 'compare' (duration: 32.467397ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:57:59.027427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:57:58.475641Z","time spent":"551.706129ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:57:59.065083Z","caller":"traceutil/trace.go:171","msg":"trace[1158334342] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:391; }","duration":"183.968905ms","start":"2026-03-31T10:57:58.881088Z","end":"2026-03-31T10:57:59.065057Z","steps":["trace[1158334342] 'read index received' (duration: 113.557159ms)","trace[1158334342] 'applied index is now lower than readState.Index' (duration: 70.410726ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:57:59.06521Z","caller":"traceutil/trace.go:171","msg":"trace[1189840570] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"538.904192ms","start":"2026-03-31T10:57:58.526251Z","end":"2026-03-31T10:57:59.065155Z","steps":["trace[1189840570] 'process raft request' (duration: 538.696026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:57:59.065278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.103691ms","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-31T10:57:59.065318Z","caller":"traceutil/trace.go:171","msg":"trace[540553157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"135.188144ms","start":"2026-03-31T10:57:58.930118Z","end":"2026-03-31T10:57:59.065307Z","steps":["trace[540553157] 'agreement among raft nodes before linearized reading' (duration: 135.07424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:57:59.065339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.223281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T10:57:59.06538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:57:58.526226Z","time spent":"539.082507ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:57:59.065414Z","caller":"traceutil/trace.go:171","msg":"trace[306260643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"184.354156ms","start":"2026-03-31T10:57:58.881038Z","end":"2026-03-31T10:57:59.065393Z","steps":["trace[306260643] 'agreement among raft nodes before linearized reading' (duration: 184.175971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:57:59.345771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.036984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:57:59.346017Z","caller":"traceutil/trace.go:171","msg":"trace[1674330852] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"275.693926ms","start":"2026-03-31T10:57:59.070298Z","end":"2026-03-31T10:57:59.345992Z","steps":["trace[1674330852] 'process raft request' (duration: 147.200789ms)","trace[1674330852] 'compare' (duration: 127.89578ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:57:59.346449Z","caller":"traceutil/trace.go:171","msg":"trace[733011246] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"276.091107ms","start":"2026-03-31T10:57:59.070343Z","end":"2026-03-31T10:57:59.346434Z","steps":["trace[733011246] 'process raft request' (duration: 275.599423ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:57:59.346545Z","caller":"traceutil/trace.go:171","msg":"trace[1119759921] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"275.17365ms","start":"2026-03-31T10:57:59.071336Z","end":"2026-03-31T10:57:59.346509Z","steps":["trace[1119759921] 'process raft request' (duration: 274.965925ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:57:59.346721Z","caller":"traceutil/trace.go:171","msg":"trace[1619731859] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:394; }","duration":"138.588928ms","start":"2026-03-31T10:57:59.208119Z","end":"2026-03-31T10:57:59.346708Z","steps":["trace[1619731859] 'read index received' (duration: 9.28978ms)","trace[1619731859] 'applied index is now lower than readState.Index' (duration: 129.295738ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:57:59.346852Z","caller":"traceutil/trace.go:171","msg":"trace[86261752] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"273.788012ms","start":"2026-03-31T10:57:59.07302Z","end":"2026-03-31T10:57:59.346808Z","steps":["trace[86261752] 'process raft request' (duration: 273.3567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:57:59.346892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.765823ms","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-31T10:57:59.346968Z","caller":"traceutil/trace.go:171","msg":"trace[619121048] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:382; }","duration":"138.875417ms","start":"2026-03-31T10:57:59.208082Z","end":"2026-03-31T10:57:59.346958Z","steps":["trace[619121048] 'agreement among raft nodes before linearized reading' (duration: 138.667371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:57:59.56032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.039857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:57:59.560713Z","caller":"traceutil/trace.go:171","msg":"trace[1990583539] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"208.812199ms","start":"2026-03-31T10:57:59.351865Z","end":"2026-03-31T10:57:59.560677Z","steps":["trace[1990583539] 'process raft request' (duration: 96.34069ms)","trace[1990583539] 'compare' (duration: 111.900013ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:57:59.560859Z","caller":"traceutil/trace.go:171","msg":"trace[80036199] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"206.68472ms","start":"2026-03-31T10:57:59.354138Z","end":"2026-03-31T10:57:59.560823Z","steps":["trace[80036199] 'process raft request' (duration: 206.486545ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:57:59.560882Z","caller":"traceutil/trace.go:171","msg":"trace[1493015813] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"205.63195ms","start":"2026-03-31T10:57:59.355233Z","end":"2026-03-31T10:57:59.560865Z","steps":["trace[1493015813] 'process raft request' (duration: 205.437525ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:57:59.561091Z","caller":"traceutil/trace.go:171","msg":"trace[2020118577] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"205.542588ms","start":"2026-03-31T10:57:59.355517Z","end":"2026-03-31T10:57:59.56106Z","steps":["trace[2020118577] 'process raft request' (duration: 205.186087ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:57:59.561259Z","caller":"traceutil/trace.go:171","msg":"trace[1045448034] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"207.781339ms","start":"2026-03-31T10:57:59.353462Z","end":"2026-03-31T10:57:59.561244Z","steps":["trace[1045448034] 'process raft request' (duration: 207.06394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:57:59.959539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.233078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:57:59.95964Z","caller":"traceutil/trace.go:171","msg":"trace[2076495991] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"206.403911ms","start":"2026-03-31T10:57:59.753223Z","end":"2026-03-31T10:57:59.959627Z","steps":["trace[2076495991] 'process raft request' (duration: 63.010849ms)","trace[2076495991] 'compare' (duration: 143.117515ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:57:59.95964Z","caller":"traceutil/trace.go:171","msg":"trace[517293411] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"138.304731ms","start":"2026-03-31T10:57:59.821315Z","end":"2026-03-31T10:57:59.95962Z","steps":["trace[517293411] 'read index received' (duration: 34.501µs)","trace[517293411] 'applied index is now lower than readState.Index' (duration: 138.26878ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:57:59.95972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.396793ms","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-31T10:57:59.959771Z","caller":"traceutil/trace.go:171","msg":"trace[2038772356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"138.495256ms","start":"2026-03-31T10:57:59.821263Z","end":"2026-03-31T10:57:59.959758Z","steps":["trace[2038772356] 'agreement among raft nodes before linearized reading' (duration: 138.401934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:00.331701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.090821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:58:00.331809Z","caller":"traceutil/trace.go:171","msg":"trace[1100374916] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"336.149002ms","start":"2026-03-31T10:57:59.995647Z","end":"2026-03-31T10:58:00.331796Z","steps":["trace[1100374916] 'process raft request' (duration: 176.897197ms)","trace[1100374916] 'compare' (duration: 158.935476ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:58:00.33185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:57:59.995612Z","time spent":"336.220845ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:58:01.825256Z","caller":"traceutil/trace.go:171","msg":"trace[1720591755] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"475.05739ms","start":"2026-03-31T10:58:01.350163Z","end":"2026-03-31T10:58:01.82522Z","steps":["trace[1720591755] 'read index received' (duration: 474.757982ms)","trace[1720591755] 'applied index is now lower than readState.Index' (duration: 297.778µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:01.825317Z","caller":"traceutil/trace.go:171","msg":"trace[1807718063] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"711.533561ms","start":"2026-03-31T10:58:01.113743Z","end":"2026-03-31T10:58:01.825277Z","steps":["trace[1807718063] 'process raft request' (duration: 711.251983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:01.825435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.253805ms","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-31T10:58:01.825514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:01.113717Z","time spent":"711.694695ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:58:01.825534Z","caller":"traceutil/trace.go:171","msg":"trace[1774978232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"475.396898ms","start":"2026-03-31T10:58:01.350113Z","end":"2026-03-31T10:58:01.82551Z","steps":["trace[1774978232] 'agreement among raft nodes before linearized reading' (duration: 475.206683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:01.825638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:01.350089Z","time spent":"475.521862ms","remote":"127.0.0.1:37062","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-31T10:58:07.168765Z","caller":"traceutil/trace.go:171","msg":"trace[1916145786] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"701.229204ms","start":"2026-03-31T10:58:06.467515Z","end":"2026-03-31T10:58:07.168745Z","steps":["trace[1916145786] 'process raft request' (duration: 700.916605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:07.168965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:06.467491Z","time spent":"701.374477ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:58:07.169134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.202897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:58:07.168642Z","caller":"traceutil/trace.go:171","msg":"trace[1641258519] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"190.716672ms","start":"2026-03-31T10:58:06.977893Z","end":"2026-03-31T10:58:07.168609Z","steps":["trace[1641258519] 'read index received' (duration: 190.430785ms)","trace[1641258519] 'applied index is now lower than readState.Index' (duration: 284.967µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:07.169265Z","caller":"traceutil/trace.go:171","msg":"trace[1100132210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"191.395112ms","start":"2026-03-31T10:58:06.977842Z","end":"2026-03-31T10:58:07.169237Z","steps":["trace[1100132210] 'agreement among raft nodes before linearized reading' (duration: 191.162696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:12.05179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.227872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:58:12.051859Z","caller":"traceutil/trace.go:171","msg":"trace[1392357613] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:462; }","duration":"185.54585ms","start":"2026-03-31T10:58:11.866304Z","end":"2026-03-31T10:58:12.05185Z","steps":["trace[1392357613] 'read index received' (duration: 73.121602ms)","trace[1392357613] 'applied index is now lower than readState.Index' (duration: 112.423528ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:12.051904Z","caller":"traceutil/trace.go:171","msg":"trace[712572772] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"196.074144ms","start":"2026-03-31T10:58:11.855823Z","end":"2026-03-31T10:58:12.051897Z","steps":["trace[712572772] 'process raft request' (duration: 83.665866ms)","trace[712572772] 'compare' (duration: 112.115079ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:58:12.052054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.481683ms","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-31T10:58:12.052118Z","caller":"traceutil/trace.go:171","msg":"trace[221214632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"182.584967ms","start":"2026-03-31T10:58:11.869517Z","end":"2026-03-31T10:58:12.052102Z","steps":["trace[221214632] 'agreement among raft nodes before linearized reading' (duration: 182.435303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:12.052247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.885789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T10:58:12.052347Z","caller":"traceutil/trace.go:171","msg":"trace[92629681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:444; }","duration":"186.053844ms","start":"2026-03-31T10:58:11.866271Z","end":"2026-03-31T10:58:12.052325Z","steps":["trace[92629681] 'agreement among raft nodes before linearized reading' (duration: 185.733795ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:12.581524Z","caller":"traceutil/trace.go:171","msg":"trace[245410099] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"388.03164ms","start":"2026-03-31T10:58:12.193458Z","end":"2026-03-31T10:58:12.58149Z","steps":["trace[245410099] 'process raft request' (duration: 366.87672ms)","trace[245410099] 'compare' (duration: 20.707908ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:58:12.581729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:12.193434Z","time spent":"388.230916ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:12.581959Z","caller":"traceutil/trace.go:171","msg":"trace[708952858] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"372.402284ms","start":"2026-03-31T10:58:12.209436Z","end":"2026-03-31T10:58:12.581838Z","steps":["trace[708952858] 'process raft request' (duration: 372.042204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:12.582183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:12.209417Z","time spent":"372.627541ms","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:58:12.629982Z","caller":"traceutil/trace.go:171","msg":"trace[1933013047] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"410.233001ms","start":"2026-03-31T10:58:12.219718Z","end":"2026-03-31T10:58:12.629951Z","steps":["trace[1933013047] 'process raft request' (duration: 410.172849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:12.630153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:12.219699Z","time spent":"410.368035ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:58:12.630131Z","caller":"traceutil/trace.go:171","msg":"trace[120564319] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"411.888358ms","start":"2026-03-31T10:58:12.218189Z","end":"2026-03-31T10:58:12.630077Z","steps":["trace[120564319] 'process raft request' (duration: 411.657582ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:12.63015Z","caller":"traceutil/trace.go:171","msg":"trace[357819919] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"412.565406ms","start":"2026-03-31T10:58:12.217559Z","end":"2026-03-31T10:58:12.630124Z","steps":["trace[357819919] 'process raft request' (duration: 412.169175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:12.630324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:12.218173Z","time spent":"412.066402ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T10:58:12.630419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:12.217531Z","time spent":"412.836104ms","remote":"127.0.0.1:36900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:12.819702Z","caller":"traceutil/trace.go:171","msg":"trace[585235478] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"184.994743ms","start":"2026-03-31T10:58:12.634677Z","end":"2026-03-31T10:58:12.819672Z","steps":["trace[585235478] 'process raft request' (duration: 116.4356ms)","trace[585235478] 'compare' (duration: 68.168863ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:12.819818Z","caller":"traceutil/trace.go:171","msg":"trace[761053414] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:476; }","duration":"183.11423ms","start":"2026-03-31T10:58:12.636674Z","end":"2026-03-31T10:58:12.819788Z","steps":["trace[761053414] 'read index received' (duration: 31.727965ms)","trace[761053414] 'applied index is now lower than readState.Index' (duration: 151.385085ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:12.819966Z","caller":"traceutil/trace.go:171","msg":"trace[2089747027] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"185.058926ms","start":"2026-03-31T10:58:12.63488Z","end":"2026-03-31T10:58:12.819939Z","steps":["trace[2089747027] 'process raft request' (duration: 184.682135ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:12.820002Z","caller":"traceutil/trace.go:171","msg":"trace[1495377486] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"183.384389ms","start":"2026-03-31T10:58:12.636603Z","end":"2026-03-31T10:58:12.819988Z","steps":["trace[1495377486] 'process raft request' (duration: 183.06764ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:12.820128Z","caller":"traceutil/trace.go:171","msg":"trace[1339489600] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"147.436825ms","start":"2026-03-31T10:58:12.67268Z","end":"2026-03-31T10:58:12.820117Z","steps":["trace[1339489600] 'process raft request' (duration: 147.071965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:12.820474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.546107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14401"} {"level":"warn","ts":"2026-03-31T10:58:12.820488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.923752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ntzlp\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-03-31T10:58:12.820537Z","caller":"traceutil/trace.go:171","msg":"trace[717078619] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:465; }","duration":"142.648431ms","start":"2026-03-31T10:58:12.677875Z","end":"2026-03-31T10:58:12.820523Z","steps":["trace[717078619] 'agreement among raft nodes before linearized reading' (duration: 142.493347ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:12.820553Z","caller":"traceutil/trace.go:171","msg":"trace[825299848] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ntzlp; range_end:; response_count:1; response_revision:465; }","duration":"147.015443ms","start":"2026-03-31T10:58:12.673519Z","end":"2026-03-31T10:58:12.820534Z","steps":["trace[825299848] 'agreement among raft nodes before linearized reading' (duration: 146.90137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:12.820648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.728738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-31T10:58:12.820707Z","caller":"traceutil/trace.go:171","msg":"trace[933547218] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:465; }","duration":"184.041947ms","start":"2026-03-31T10:58:12.636647Z","end":"2026-03-31T10:58:12.820689Z","steps":["trace[933547218] 'agreement among raft nodes before linearized reading' (duration: 183.717648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:12.820887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.71726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-03-31T10:58:12.820996Z","caller":"traceutil/trace.go:171","msg":"trace[1366790064] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:465; }","duration":"153.884875ms","start":"2026-03-31T10:58:12.667084Z","end":"2026-03-31T10:58:12.820969Z","steps":["trace[1366790064] 'agreement among raft nodes before linearized reading' (duration: 153.206146ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:12.955921Z","caller":"traceutil/trace.go:171","msg":"trace[1576032045] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"112.552913ms","start":"2026-03-31T10:58:12.843338Z","end":"2026-03-31T10:58:12.955891Z","steps":["trace[1576032045] 'process raft request' (duration: 80.748505ms)","trace[1576032045] 'compare' (duration: 31.625232ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:12.956093Z","caller":"traceutil/trace.go:171","msg":"trace[545037290] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"106.974317ms","start":"2026-03-31T10:58:12.84909Z","end":"2026-03-31T10:58:12.956064Z","steps":["trace[545037290] 'process raft request' (duration: 106.754661ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:14.782547Z","caller":"traceutil/trace.go:171","msg":"trace[45912966] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"475.323867ms","start":"2026-03-31T10:58:14.307196Z","end":"2026-03-31T10:58:14.78252Z","steps":["trace[45912966] 'process raft request' (duration: 475.177633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:14.782701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.307165Z","time spent":"475.461651ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:14.79038Z","caller":"traceutil/trace.go:171","msg":"trace[1109691698] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"474.095452ms","start":"2026-03-31T10:58:14.316266Z","end":"2026-03-31T10:58:14.790361Z","steps":["trace[1109691698] 'process raft request' (duration: 473.99279ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:14.790405Z","caller":"traceutil/trace.go:171","msg":"trace[289157378] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"474.188804ms","start":"2026-03-31T10:58:14.316169Z","end":"2026-03-31T10:58:14.790358Z","steps":["trace[289157378] 'process raft request' (duration: 473.936508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:14.790496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.316258Z","time spent":"474.183535ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T10:58:14.790586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.31614Z","time spent":"474.353729ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:14.790668Z","caller":"traceutil/trace.go:171","msg":"trace[940910248] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"449.064594ms","start":"2026-03-31T10:58:14.341584Z","end":"2026-03-31T10:58:14.790649Z","steps":["trace[940910248] 'process raft request' (duration: 448.720134ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:14.790716Z","caller":"traceutil/trace.go:171","msg":"trace[1709952738] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"447.909861ms","start":"2026-03-31T10:58:14.34277Z","end":"2026-03-31T10:58:14.79068Z","steps":["trace[1709952738] 'process raft request' (duration: 447.574522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:14.790762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.341563Z","time spent":"449.152466ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T10:58:14.790847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.342744Z","time spent":"448.049056ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:14.790844Z","caller":"traceutil/trace.go:171","msg":"trace[2065190555] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"446.910514ms","start":"2026-03-31T10:58:14.343884Z","end":"2026-03-31T10:58:14.790794Z","steps":["trace[2065190555] 'process raft request' (duration: 446.533464ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:14.790999Z","caller":"traceutil/trace.go:171","msg":"trace[184062152] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"446.838871ms","start":"2026-03-31T10:58:14.344149Z","end":"2026-03-31T10:58:14.790988Z","steps":["trace[184062152] 'process raft request' (duration: 446.309736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:14.791043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.344137Z","time spent":"446.887892ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T10:58:14.79233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.34387Z","time spent":"447.032737ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:14.792476Z","caller":"traceutil/trace.go:171","msg":"trace[435284391] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"447.710957ms","start":"2026-03-31T10:58:14.34475Z","end":"2026-03-31T10:58:14.792461Z","steps":["trace[435284391] 'process raft request' (duration: 446.143392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:14.792523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.344734Z","time spent":"447.766227ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:14.79253Z","caller":"traceutil/trace.go:171","msg":"trace[836893254] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"437.282895ms","start":"2026-03-31T10:58:14.355231Z","end":"2026-03-31T10:58:14.792513Z","steps":["trace[836893254] 'process raft request' (duration: 437.080449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:14.79259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.355197Z","time spent":"437.367038ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:14.792603Z","caller":"traceutil/trace.go:171","msg":"trace[1988342643] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"435.714032ms","start":"2026-03-31T10:58:14.356873Z","end":"2026-03-31T10:58:14.792587Z","steps":["trace[1988342643] 'process raft request' (duration: 435.505536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:14.792671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.356849Z","time spent":"435.795603ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:14.792744Z","caller":"traceutil/trace.go:171","msg":"trace[769808410] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"419.594132ms","start":"2026-03-31T10:58:14.373142Z","end":"2026-03-31T10:58:14.792736Z","steps":["trace[769808410] 'process raft request' (duration: 419.351535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:14.792801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.373123Z","time spent":"419.644172ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:14.793029Z","caller":"traceutil/trace.go:171","msg":"trace[1270544495] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"311.35694ms","start":"2026-03-31T10:58:14.481661Z","end":"2026-03-31T10:58:14.793018Z","steps":["trace[1270544495] 'process raft request' (duration: 310.949728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:14.793251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:14.481628Z","time spent":"311.603358ms","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:21.151974Z","caller":"traceutil/trace.go:171","msg":"trace[531388181] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"177.295749ms","start":"2026-03-31T10:58:20.974652Z","end":"2026-03-31T10:58:21.151948Z","steps":["trace[531388181] 'read index received' (duration: 130.957335ms)","trace[531388181] 'applied index is now lower than readState.Index' (duration: 46.337274ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:58:21.152131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.465273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:58:21.152171Z","caller":"traceutil/trace.go:171","msg":"trace[1144345612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"177.565496ms","start":"2026-03-31T10:58:20.974594Z","end":"2026-03-31T10:58:21.152159Z","steps":["trace[1144345612] 'agreement among raft nodes before linearized reading' (duration: 177.459623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:21.152141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.085366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ntzlp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-31T10:58:21.152326Z","caller":"traceutil/trace.go:171","msg":"trace[703123308] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ntzlp; range_end:; response_count:1; response_revision:585; }","duration":"228.400345ms","start":"2026-03-31T10:58:20.923901Z","end":"2026-03-31T10:58:21.152301Z","steps":["trace[703123308] 'range keys from in-memory index tree' (duration: 227.885911ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:21.152191Z","caller":"traceutil/trace.go:171","msg":"trace[167417292] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"201.566947ms","start":"2026-03-31T10:58:20.950549Z","end":"2026-03-31T10:58:21.152116Z","steps":["trace[167417292] 'process raft request' (duration: 155.184622ms)","trace[167417292] 'compare' (duration: 46.084166ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:22.004744Z","caller":"traceutil/trace.go:171","msg":"trace[1938820383] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"835.221505ms","start":"2026-03-31T10:58:21.169487Z","end":"2026-03-31T10:58:22.004708Z","steps":["trace[1938820383] 'process raft request' (duration: 835.03238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:22.00495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:21.169457Z","time spent":"835.40998ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:58:22.878743Z","caller":"traceutil/trace.go:171","msg":"trace[109709771] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"901.449534ms","start":"2026-03-31T10:58:21.977267Z","end":"2026-03-31T10:58:22.878717Z","steps":["trace[109709771] 'read index received' (duration: 27.284471ms)","trace[109709771] 'applied index is now lower than readState.Index' (duration: 874.163793ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:22.87884Z","caller":"traceutil/trace.go:171","msg":"trace[1842609431] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.319945137s","start":"2026-03-31T10:58:21.558862Z","end":"2026-03-31T10:58:22.878807Z","steps":["trace[1842609431] 'process raft request' (duration: 1.318554478s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:22.878924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.64287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:58:22.878964Z","caller":"traceutil/trace.go:171","msg":"trace[40931376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"901.757464ms","start":"2026-03-31T10:58:21.977196Z","end":"2026-03-31T10:58:22.878954Z","steps":["trace[40931376] 'agreement among raft nodes before linearized reading' (duration: 901.671002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:22.878999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:21.977161Z","time spent":"901.830555ms","remote":"127.0.0.1:36824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T10:58:22.879038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:21.55881Z","time spent":"1.320104332s","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036401,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:58:22.879273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.940541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T10:58:22.8793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.820847ms","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-31T10:58:22.879389Z","caller":"traceutil/trace.go:171","msg":"trace[96069102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"752.138307ms","start":"2026-03-31T10:58:22.127222Z","end":"2026-03-31T10:58:22.87936Z","steps":["trace[96069102] 'agreement among raft nodes before linearized reading' (duration: 751.740127ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:22.87941Z","caller":"traceutil/trace.go:171","msg":"trace[1508958698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"708.962801ms","start":"2026-03-31T10:58:22.170415Z","end":"2026-03-31T10:58:22.879378Z","steps":["trace[1508958698] 'agreement among raft nodes before linearized reading' (duration: 708.620011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:22.879456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:22.127191Z","time spent":"752.254161ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T10:58:22.879461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:22.170405Z","time spent":"709.047785ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T10:58:22.879521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.760515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T10:58:22.880616Z","caller":"traceutil/trace.go:171","msg":"trace[2137639928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"709.843877ms","start":"2026-03-31T10:58:22.169715Z","end":"2026-03-31T10:58:22.879558Z","steps":["trace[2137639928] 'agreement among raft nodes before linearized reading' (duration: 709.658722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:22.880956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:22.169687Z","time spent":"711.214445ms","remote":"127.0.0.1:37062","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-31T10:58:22.881569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.87383ms","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-31T10:58:22.881672Z","caller":"traceutil/trace.go:171","msg":"trace[1670692461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"703.983753ms","start":"2026-03-31T10:58:22.177647Z","end":"2026-03-31T10:58:22.88163Z","steps":["trace[1670692461] 'agreement among raft nodes before linearized reading' (duration: 701.38383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:22.881722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:22.17763Z","time spent":"704.081385ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T10:58:24.477804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201285902657578,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T10:58:24.978322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201285902657578,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T10:58:25.010047Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.118181668s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-31T10:58:25.118261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.778618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:58:25.118418Z","caller":"traceutil/trace.go:171","msg":"trace[1389623727] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"2.226635544s","start":"2026-03-31T10:58:22.891757Z","end":"2026-03-31T10:58:25.118392Z","steps":["trace[1389623727] 'process raft request' (duration: 2.118610549s)","trace[1389623727] 'compare' (duration: 107.49335ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:58:25.118494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:22.891733Z","time spent":"2.226725948s","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:58:25.121242Z","caller":"traceutil/trace.go:171","msg":"trace[913334952] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"2.229071083s","start":"2026-03-31T10:58:22.892146Z","end":"2026-03-31T10:58:25.121218Z","steps":["trace[913334952] 'process raft request' (duration: 2.22894279s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:25.121393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:22.89214Z","time spent":"2.229163326s","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:58:25.121556Z","caller":"traceutil/trace.go:171","msg":"trace[1062337051] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"2.2293331s","start":"2026-03-31T10:58:22.892186Z","end":"2026-03-31T10:58:25.121519Z","steps":["trace[1062337051] 'process raft request' (duration: 2.22900097s)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:25.12159Z","caller":"traceutil/trace.go:171","msg":"trace[1746249536] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"2.229096515s","start":"2026-03-31T10:58:22.892484Z","end":"2026-03-31T10:58:25.12158Z","steps":["trace[1746249536] 'process raft request' (duration: 2.22893872s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:25.121641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:22.892478Z","time spent":"2.229137105s","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:58:25.121654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:22.892182Z","time spent":"2.229426442s","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:58:25.121697Z","caller":"traceutil/trace.go:171","msg":"trace[1202109792] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"2.175571531s","start":"2026-03-31T10:58:22.946119Z","end":"2026-03-31T10:58:25.121691Z","steps":["trace[1202109792] 'process raft request' (duration: 2.175380805s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:25.12173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:22.946085Z","time spent":"2.175628051s","remote":"127.0.0.1:37040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T10:58:25.121717Z","caller":"traceutil/trace.go:171","msg":"trace[1142388753] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:612; }","duration":"1.144610753s","start":"2026-03-31T10:58:23.977077Z","end":"2026-03-31T10:58:25.121688Z","steps":["trace[1142388753] 'read index received' (duration: 1.033185712s)","trace[1142388753] 'applied index is now lower than readState.Index' (duration: 111.424151ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:25.121734Z","caller":"traceutil/trace.go:171","msg":"trace[1617416843] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"2.028204016s","start":"2026-03-31T10:58:23.093506Z","end":"2026-03-31T10:58:25.12171Z","steps":["trace[1617416843] 'process raft request' (duration: 2.028105603s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:25.121864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:23.093484Z","time spent":"2.028305689s","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:58:25.121891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.26643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-31T10:58:25.121872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144800518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:58:25.121931Z","caller":"traceutil/trace.go:171","msg":"trace[2142827075] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:594; }","duration":"982.363043ms","start":"2026-03-31T10:58:24.139557Z","end":"2026-03-31T10:58:25.12192Z","steps":["trace[2142827075] 'agreement among raft nodes before linearized reading' (duration: 982.26658ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:25.121994Z","caller":"traceutil/trace.go:171","msg":"trace[89022579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"1.144923831s","start":"2026-03-31T10:58:23.977023Z","end":"2026-03-31T10:58:25.121947Z","steps":["trace[89022579] 'agreement among raft nodes before linearized reading' (duration: 1.144788637s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:25.122007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:24.13953Z","time spent":"982.466186ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"warn","ts":"2026-03-31T10:58:25.122055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:23.977003Z","time spent":"1.145038724s","remote":"127.0.0.1:36824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T10:58:30.342472Z","caller":"traceutil/trace.go:171","msg":"trace[1152096929] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"137.657482ms","start":"2026-03-31T10:58:30.204788Z","end":"2026-03-31T10:58:30.342445Z","steps":["trace[1152096929] 'process raft request' (duration: 137.464546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:39.213166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.637608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:58:39.213317Z","caller":"traceutil/trace.go:171","msg":"trace[1549330440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"235.877914ms","start":"2026-03-31T10:58:38.977411Z","end":"2026-03-31T10:58:39.213289Z","steps":["trace[1549330440] 'range keys from in-memory index tree' (duration: 235.455254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:43.358483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201285902657907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T10:58:43.469722Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.433041329s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T10:58:43.470359Z","caller":"traceutil/trace.go:171","msg":"trace[1987804516] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"612.355023ms","start":"2026-03-31T10:58:42.857973Z","end":"2026-03-31T10:58:43.470328Z","steps":["trace[1987804516] 'read index received' (duration: 612.067324ms)","trace[1987804516] 'applied index is now lower than readState.Index' (duration: 286.509µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:43.470466Z","caller":"traceutil/trace.go:171","msg":"trace[1480027524] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"1.433960246s","start":"2026-03-31T10:58:42.036493Z","end":"2026-03-31T10:58:43.470453Z","steps":["trace[1480027524] 'process raft request' (duration: 1.433597526s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:43.470628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:42.036454Z","time spent":"1.434048727s","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:58:43.470756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.935458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:58:43.470895Z","caller":"traceutil/trace.go:171","msg":"trace[1813957264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"494.188195ms","start":"2026-03-31T10:58:42.976677Z","end":"2026-03-31T10:58:43.470865Z","steps":["trace[1813957264] 'agreement among raft nodes before linearized reading' (duration: 493.921107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:43.470815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.447894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T10:58:43.470952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.986619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-31T10:58:43.470938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.852035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-31T10:58:43.470909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.506484ms","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-31T10:58:43.471081Z","caller":"traceutil/trace.go:171","msg":"trace[734210116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"149.04658ms","start":"2026-03-31T10:58:43.322011Z","end":"2026-03-31T10:58:43.471057Z","steps":["trace[734210116] 'agreement among raft nodes before linearized reading' (duration: 148.7166ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:43.471109Z","caller":"traceutil/trace.go:171","msg":"trace[777228180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:757; }","duration":"177.726432ms","start":"2026-03-31T10:58:43.293351Z","end":"2026-03-31T10:58:43.471077Z","steps":["trace[777228180] 'agreement among raft nodes before linearized reading' (duration: 177.441813ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:43.471014Z","caller":"traceutil/trace.go:171","msg":"trace[1441277503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"138.693161ms","start":"2026-03-31T10:58:43.332304Z","end":"2026-03-31T10:58:43.470997Z","steps":["trace[1441277503] 'agreement among raft nodes before linearized reading' (duration: 138.342932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:43.470938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.463284ms","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-31T10:58:43.47159Z","caller":"traceutil/trace.go:171","msg":"trace[2071218365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"139.136334ms","start":"2026-03-31T10:58:43.332433Z","end":"2026-03-31T10:58:43.471569Z","steps":["trace[2071218365] 'agreement among raft nodes before linearized reading' (duration: 138.426975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:43.471101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:42.97666Z","time spent":"494.371729ms","remote":"127.0.0.1:36824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T10:58:43.471021Z","caller":"traceutil/trace.go:171","msg":"trace[622416374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"613.094874ms","start":"2026-03-31T10:58:42.857918Z","end":"2026-03-31T10:58:43.471013Z","steps":["trace[622416374] 'agreement among raft nodes before linearized reading' (duration: 612.911118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:43.472103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:42.857897Z","time spent":"614.143113ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T10:58:47.38467Z","caller":"traceutil/trace.go:171","msg":"trace[1636077458] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"241.028817ms","start":"2026-03-31T10:58:47.143607Z","end":"2026-03-31T10:58:47.384636Z","steps":["trace[1636077458] 'process raft request' (duration: 240.576525ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:47.511513Z","caller":"traceutil/trace.go:171","msg":"trace[277846690] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"213.394437ms","start":"2026-03-31T10:58:47.298093Z","end":"2026-03-31T10:58:47.511487Z","steps":["trace[277846690] 'process raft request' (duration: 213.233542ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:47.529802Z","caller":"traceutil/trace.go:171","msg":"trace[1645508704] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"139.537075ms","start":"2026-03-31T10:58:47.390234Z","end":"2026-03-31T10:58:47.529771Z","steps":["trace[1645508704] 'process raft request' (duration: 139.351129ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:03.427693Z","caller":"traceutil/trace.go:171","msg":"trace[1139942088] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"122.056727ms","start":"2026-03-31T10:59:03.305612Z","end":"2026-03-31T10:59:03.427669Z","steps":["trace[1139942088] 'read index received' (duration: 121.879122ms)","trace[1139942088] 'applied index is now lower than readState.Index' (duration: 176.525µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:03.427723Z","caller":"traceutil/trace.go:171","msg":"trace[168449268] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"143.558227ms","start":"2026-03-31T10:59:03.284131Z","end":"2026-03-31T10:59:03.427689Z","steps":["trace[168449268] 'process raft request' (duration: 143.366041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:03.427958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.323214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io\" ","response":"range_response_count:1 size:26919"} {"level":"info","ts":"2026-03-31T10:59:03.428013Z","caller":"traceutil/trace.go:171","msg":"trace[131874179] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io; range_end:; response_count:1; response_revision:1089; }","duration":"122.415838ms","start":"2026-03-31T10:59:03.305582Z","end":"2026-03-31T10:59:03.427998Z","steps":["trace[131874179] 'agreement among raft nodes before linearized reading' (duration: 122.196161ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:14.212023Z","caller":"traceutil/trace.go:171","msg":"trace[1881980991] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1194; }","duration":"465.600056ms","start":"2026-03-31T10:59:13.746389Z","end":"2026-03-31T10:59:14.211989Z","steps":["trace[1881980991] 'read index received' (duration: 465.413481ms)","trace[1881980991] 'applied index is now lower than readState.Index' (duration: 184.555µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:14.212091Z","caller":"traceutil/trace.go:171","msg":"trace[418953017] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"480.711408ms","start":"2026-03-31T10:59:13.731322Z","end":"2026-03-31T10:59:14.212033Z","steps":["trace[418953017] 'process raft request' (duration: 480.369668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.212492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.52079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T10:59:14.212562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.532705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T10:59:14.212579Z","caller":"traceutil/trace.go:171","msg":"trace[661235100] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1159; }","duration":"180.656844ms","start":"2026-03-31T10:59:14.031904Z","end":"2026-03-31T10:59:14.212561Z","steps":["trace[661235100] 'agreement among raft nodes before linearized reading' (duration: 180.316633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.212614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.219724ms","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-31T10:59:14.212652Z","caller":"traceutil/trace.go:171","msg":"trace[661465037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"466.291964ms","start":"2026-03-31T10:59:13.746351Z","end":"2026-03-31T10:59:14.212643Z","steps":["trace[661465037] 'agreement among raft nodes before linearized reading' (duration: 465.778181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.212658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.820725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T10:59:14.212675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:13.746333Z","time spent":"466.334108ms","remote":"127.0.0.1:37062","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":"info","ts":"2026-03-31T10:59:14.212687Z","caller":"traceutil/trace.go:171","msg":"trace[1007352326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"238.892499ms","start":"2026-03-31T10:59:13.973787Z","end":"2026-03-31T10:59:14.21268Z","steps":["trace[1007352326] 'agreement among raft nodes before linearized reading' (duration: 238.849757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.212732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.194786ms","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-31T10:59:14.212615Z","caller":"traceutil/trace.go:171","msg":"trace[2056060102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"465.637418ms","start":"2026-03-31T10:59:13.746965Z","end":"2026-03-31T10:59:14.212602Z","steps":["trace[2056060102] 'agreement among raft nodes before linearized reading' (duration: 465.233136ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:14.212777Z","caller":"traceutil/trace.go:171","msg":"trace[1192305231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1159; }","duration":"412.254978ms","start":"2026-03-31T10:59:13.800508Z","end":"2026-03-31T10:59:14.212763Z","steps":["trace[1192305231] 'agreement among raft nodes before linearized reading' (duration: 412.131804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.212857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:13.800496Z","time spent":"412.35268ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T10:59:14.212881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.337939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T10:59:14.212989Z","caller":"traceutil/trace.go:171","msg":"trace[2092338612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"465.470892ms","start":"2026-03-31T10:59:13.74749Z","end":"2026-03-31T10:59:14.212961Z","steps":["trace[2092338612] 'agreement among raft nodes before linearized reading' (duration: 465.153753ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.213029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:13.747478Z","time spent":"465.542374ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T10:59:14.212984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:13.746953Z","time spent":"465.919985ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T10:59:14.213096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:13.731304Z","time spent":"480.921184ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:14.558485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:14.214861Z","time spent":"343.613ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-31T10:59:14.882381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.846734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:59:14.882635Z","caller":"traceutil/trace.go:171","msg":"trace[356033457] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"664.050565ms","start":"2026-03-31T10:59:14.218547Z","end":"2026-03-31T10:59:14.882598Z","steps":["trace[356033457] 'process raft request' (duration: 533.899983ms)","trace[356033457] 'compare' (duration: 129.729601ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:14.882746Z","caller":"traceutil/trace.go:171","msg":"trace[1824551199] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"323.010466ms","start":"2026-03-31T10:59:14.559712Z","end":"2026-03-31T10:59:14.882722Z","steps":["trace[1824551199] 'process raft request' (duration: 322.966735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.882805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:14.218521Z","time spent":"664.198199ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:59:14.882807Z","caller":"traceutil/trace.go:171","msg":"trace[1460029636] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"664.114348ms","start":"2026-03-31T10:59:14.21867Z","end":"2026-03-31T10:59:14.882784Z","steps":["trace[1460029636] 'process raft request' (duration: 663.912522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.882843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:14.559683Z","time spent":"323.119039ms","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:14.882932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:14.218665Z","time spent":"664.20152ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:59:14.88294Z","caller":"traceutil/trace.go:171","msg":"trace[1731815847] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"664.293472ms","start":"2026-03-31T10:59:14.218636Z","end":"2026-03-31T10:59:14.882929Z","steps":["trace[1731815847] 'process raft request' (duration: 663.87092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.883008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:14.218631Z","time spent":"664.343753ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:59:14.88301Z","caller":"traceutil/trace.go:171","msg":"trace[299732535] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"663.719936ms","start":"2026-03-31T10:59:14.219234Z","end":"2026-03-31T10:59:14.882953Z","steps":["trace[299732535] 'process raft request' (duration: 663.380727ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:14.883044Z","caller":"traceutil/trace.go:171","msg":"trace[1495874420] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"589.749932ms","start":"2026-03-31T10:59:14.293233Z","end":"2026-03-31T10:59:14.882983Z","steps":["trace[1495874420] 'process raft request' (duration: 589.410642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:14.883307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:14.219222Z","time spent":"663.921462ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:14.883355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:14.293202Z","time spent":"590.020069ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:59:15.53734Z","caller":"traceutil/trace.go:171","msg":"trace[1674435629] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"285.357174ms","start":"2026-03-31T10:59:15.251946Z","end":"2026-03-31T10:59:15.537303Z","steps":["trace[1674435629] 'process raft request' (duration: 285.034935ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:15.978741Z","caller":"traceutil/trace.go:171","msg":"trace[1698792515] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"162.165666ms","start":"2026-03-31T10:59:15.816553Z","end":"2026-03-31T10:59:15.978719Z","steps":["trace[1698792515] 'read index received' (duration: 121.43612ms)","trace[1698792515] 'applied index is now lower than readState.Index' (duration: 40.728566ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:15.978775Z","caller":"traceutil/trace.go:171","msg":"trace[1589109923] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"378.937127ms","start":"2026-03-31T10:59:15.599797Z","end":"2026-03-31T10:59:15.978734Z","steps":["trace[1589109923] 'process raft request' (duration: 338.319353ms)","trace[1589109923] 'compare' (duration: 40.276504ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:15.978965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:15.599761Z","time spent":"379.102261ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:15.979095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.541176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-fg22m\" ","response":"range_response_count:1 size:4236"} {"level":"info","ts":"2026-03-31T10:59:15.979161Z","caller":"traceutil/trace.go:171","msg":"trace[341976750] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-fg22m; range_end:; response_count:1; response_revision:1167; }","duration":"162.649759ms","start":"2026-03-31T10:59:15.816481Z","end":"2026-03-31T10:59:15.979131Z","steps":["trace[341976750] 'agreement among raft nodes before linearized reading' (duration: 162.359071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:16.565915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.87282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T10:59:16.565987Z","caller":"traceutil/trace.go:171","msg":"trace[1766351149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1167; }","duration":"347.996393ms","start":"2026-03-31T10:59:16.217974Z","end":"2026-03-31T10:59:16.56597Z","steps":["trace[1766351149] 'range keys from in-memory index tree' (duration: 347.703895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:16.566026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:16.217953Z","time spent":"348.064675ms","remote":"127.0.0.1:37062","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-31T10:59:16.681655Z","caller":"traceutil/trace.go:171","msg":"trace[473284582] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"133.928879ms","start":"2026-03-31T10:59:16.547438Z","end":"2026-03-31T10:59:16.681367Z","steps":["trace[473284582] 'process raft request' (duration: 133.737043ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:16.703205Z","caller":"traceutil/trace.go:171","msg":"trace[419586952] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"132.599311ms","start":"2026-03-31T10:59:16.570573Z","end":"2026-03-31T10:59:16.703172Z","steps":["trace[419586952] 'process raft request' (duration: 132.281362ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:17.208177Z","caller":"traceutil/trace.go:171","msg":"trace[141170848] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"147.099166ms","start":"2026-03-31T10:59:17.061044Z","end":"2026-03-31T10:59:17.208143Z","steps":["trace[141170848] 'process raft request' (duration: 146.87483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:17.560776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.318961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:59:17.560953Z","caller":"traceutil/trace.go:171","msg":"trace[1017269216] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"306.883986ms","start":"2026-03-31T10:59:17.254046Z","end":"2026-03-31T10:59:17.56093Z","steps":["trace[1017269216] 'read index received' (duration: 125.353469ms)","trace[1017269216] 'applied index is now lower than readState.Index' (duration: 181.528867ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:17.560971Z","caller":"traceutil/trace.go:171","msg":"trace[1834254761] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"333.848898ms","start":"2026-03-31T10:59:17.227083Z","end":"2026-03-31T10:59:17.560932Z","steps":["trace[1834254761] 'process raft request' (duration: 152.345221ms)","trace[1834254761] 'compare' (duration: 180.952652ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:17.561197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.155243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-fg22m\" ","response":"range_response_count:1 size:4236"} {"level":"info","ts":"2026-03-31T10:59:17.561245Z","caller":"traceutil/trace.go:171","msg":"trace[769789906] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-fg22m; range_end:; response_count:1; response_revision:1177; }","duration":"307.239946ms","start":"2026-03-31T10:59:17.25399Z","end":"2026-03-31T10:59:17.56123Z","steps":["trace[769789906] 'agreement among raft nodes before linearized reading' (duration: 307.054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:17.561283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:17.25396Z","time spent":"307.314919ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":4259,"request content":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-fg22m\" "} {"level":"warn","ts":"2026-03-31T10:59:17.561294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.748506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T10:59:17.561362Z","caller":"traceutil/trace.go:171","msg":"trace[977142605] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1177; }","duration":"290.859719ms","start":"2026-03-31T10:59:17.27049Z","end":"2026-03-31T10:59:17.56135Z","steps":["trace[977142605] 'agreement among raft nodes before linearized reading' (duration: 290.723694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:17.561301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:17.227052Z","time spent":"334.001172ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:18.107286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.080938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T10:59:18.107593Z","caller":"traceutil/trace.go:171","msg":"trace[669004437] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1178; }","duration":"544.201241ms","start":"2026-03-31T10:59:17.563375Z","end":"2026-03-31T10:59:18.107576Z","steps":["trace[669004437] 'process raft request' (duration: 110.644029ms)","trace[669004437] 'compare' (duration: 432.670086ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:18.107643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:17.563347Z","time spent":"544.275112ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-03-31T10:59:18.166085Z","caller":"traceutil/trace.go:171","msg":"trace[895846589] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1215; }","duration":"190.185638ms","start":"2026-03-31T10:59:17.975857Z","end":"2026-03-31T10:59:18.166043Z","steps":["trace[895846589] 'read index received' (duration: 189.969682ms)","trace[895846589] 'applied index is now lower than readState.Index' (duration: 214.896µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:18.166157Z","caller":"traceutil/trace.go:171","msg":"trace[2052249203] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"595.852432ms","start":"2026-03-31T10:59:17.570265Z","end":"2026-03-31T10:59:18.166118Z","steps":["trace[2052249203] 'process raft request' (duration: 595.597725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:18.1663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.422346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T10:59:18.166368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:17.570239Z","time spent":"595.993115ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4223,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:59:18.166432Z","caller":"traceutil/trace.go:171","msg":"trace[391232582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1179; }","duration":"190.545449ms","start":"2026-03-31T10:59:17.975841Z","end":"2026-03-31T10:59:18.166386Z","steps":["trace[391232582] 'agreement among raft nodes before linearized reading' (duration: 190.353223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:18.467004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.207255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:59:18.46714Z","caller":"traceutil/trace.go:171","msg":"trace[2001069573] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"300.94915ms","start":"2026-03-31T10:59:18.166173Z","end":"2026-03-31T10:59:18.467122Z","steps":["trace[2001069573] 'read index received' (duration: 180.504758ms)","trace[2001069573] 'applied index is now lower than readState.Index' (duration: 120.443082ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:18.467215Z","caller":"traceutil/trace.go:171","msg":"trace[610004311] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"476.426709ms","start":"2026-03-31T10:59:17.990746Z","end":"2026-03-31T10:59:18.467172Z","steps":["trace[610004311] 'process raft request' (duration: 355.921485ms)","trace[610004311] 'compare' (duration: 119.941867ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:18.467267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.935292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:59:18.4673Z","caller":"traceutil/trace.go:171","msg":"trace[1618437464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1180; }","duration":"300.014645ms","start":"2026-03-31T10:59:18.167276Z","end":"2026-03-31T10:59:18.467291Z","steps":["trace[1618437464] 'agreement among raft nodes before linearized reading' (duration: 299.949403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:18.467336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:18.167259Z","time spent":"300.069325ms","remote":"127.0.0.1:36834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T10:59:18.467402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:17.990698Z","time spent":"476.604764ms","remote":"127.0.0.1:36986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6454,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:18.467419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.415099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2951"} {"level":"info","ts":"2026-03-31T10:59:18.467493Z","caller":"traceutil/trace.go:171","msg":"trace[1210044442] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1180; }","duration":"356.536121ms","start":"2026-03-31T10:59:18.110937Z","end":"2026-03-31T10:59:18.467473Z","steps":["trace[1210044442] 'agreement among raft nodes before linearized reading' (duration: 356.247424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:18.467548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:18.110912Z","time spent":"356.626643ms","remote":"127.0.0.1:37040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":2974,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-03-31T10:59:18.913206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.928914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T10:59:18.913711Z","caller":"traceutil/trace.go:171","msg":"trace[272833322] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"148.933077ms","start":"2026-03-31T10:59:18.764751Z","end":"2026-03-31T10:59:18.913685Z","steps":["trace[272833322] 'process raft request' (duration: 148.645528ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:18.913704Z","caller":"traceutil/trace.go:171","msg":"trace[2083787744] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"205.709883ms","start":"2026-03-31T10:59:18.707955Z","end":"2026-03-31T10:59:18.913665Z","steps":["trace[2083787744] 'read index received' (duration: 40.532µs)","trace[2083787744] 'applied index is now lower than readState.Index' (duration: 205.666171ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:18.913763Z","caller":"traceutil/trace.go:171","msg":"trace[831298943] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1181; }","duration":"435.347711ms","start":"2026-03-31T10:59:18.478356Z","end":"2026-03-31T10:59:18.913704Z","steps":["trace[831298943] 'process raft request' (duration: 202.771759ms)","trace[831298943] 'compare' (duration: 231.42349ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:18.913938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.96233ms","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-31T10:59:18.913989Z","caller":"traceutil/trace.go:171","msg":"trace[965240258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"206.079752ms","start":"2026-03-31T10:59:18.707898Z","end":"2026-03-31T10:59:18.913978Z","steps":["trace[965240258] 'agreement among raft nodes before linearized reading' (duration: 205.884007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:18.914174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:18.478336Z","time spent":"435.646901ms","remote":"127.0.0.1:37040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-03-31T10:59:19.239978Z","caller":"traceutil/trace.go:171","msg":"trace[1327259224] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1219; }","duration":"268.203866ms","start":"2026-03-31T10:59:18.971751Z","end":"2026-03-31T10:59:19.239955Z","steps":["trace[1327259224] 'read index received' (duration: 25.530643ms)","trace[1327259224] 'applied index is now lower than readState.Index' (duration: 242.672283ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:19.240093Z","caller":"traceutil/trace.go:171","msg":"trace[1310341897] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"320.527286ms","start":"2026-03-31T10:59:18.919531Z","end":"2026-03-31T10:59:19.240058Z","steps":["trace[1310341897] 'process raft request' (duration: 316.193155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:19.240286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:18.919502Z","time spent":"320.66571ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:19.240273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.487064ms","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-31T10:59:19.240454Z","caller":"traceutil/trace.go:171","msg":"trace[1059402247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1184; }","duration":"268.750782ms","start":"2026-03-31T10:59:18.97168Z","end":"2026-03-31T10:59:19.240431Z","steps":["trace[1059402247] 'agreement among raft nodes before linearized reading' (duration: 268.381621ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:19.307855Z","caller":"traceutil/trace.go:171","msg":"trace[2120822315] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"134.766092ms","start":"2026-03-31T10:59:19.173057Z","end":"2026-03-31T10:59:19.307823Z","steps":["trace[2120822315] 'process raft request' (duration: 134.68685ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:19.307897Z","caller":"traceutil/trace.go:171","msg":"trace[2045221506] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"160.14989ms","start":"2026-03-31T10:59:19.147726Z","end":"2026-03-31T10:59:19.307875Z","steps":["trace[2045221506] 'process raft request' (duration: 159.884103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:19.307949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.451482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T10:59:19.308024Z","caller":"traceutil/trace.go:171","msg":"trace[613565933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1186; }","duration":"335.561287ms","start":"2026-03-31T10:59:18.972444Z","end":"2026-03-31T10:59:19.308006Z","steps":["trace[613565933] 'agreement among raft nodes before linearized reading' (duration: 335.351551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:19.308068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:18.972434Z","time spent":"335.624939ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T10:59:19.308155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.067531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:59:19.308239Z","caller":"traceutil/trace.go:171","msg":"trace[1771292872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"331.191415ms","start":"2026-03-31T10:59:18.977026Z","end":"2026-03-31T10:59:19.308218Z","steps":["trace[1771292872] 'agreement among raft nodes before linearized reading' (duration: 331.02064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:19.30829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:18.976968Z","time spent":"331.312539ms","remote":"127.0.0.1:36824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T10:59:19.308367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.668238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T10:59:19.308426Z","caller":"traceutil/trace.go:171","msg":"trace[1921437196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1186; }","duration":"335.791003ms","start":"2026-03-31T10:59:18.972621Z","end":"2026-03-31T10:59:19.308412Z","steps":["trace[1921437196] 'agreement among raft nodes before linearized reading' (duration: 335.607018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:19.308467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:18.97259Z","time spent":"335.869214ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T10:59:19.308877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.107842ms","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-31T10:59:19.30892Z","caller":"traceutil/trace.go:171","msg":"trace[2052663497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1186; }","duration":"336.229725ms","start":"2026-03-31T10:59:18.972677Z","end":"2026-03-31T10:59:19.308906Z","steps":["trace[2052663497] 'agreement among raft nodes before linearized reading' (duration: 336.040719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:19.308949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:18.972643Z","time spent":"336.299567ms","remote":"127.0.0.1:37062","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-31T10:59:19.617205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.385835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:59:19.617318Z","caller":"traceutil/trace.go:171","msg":"trace[790115343] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"307.667558ms","start":"2026-03-31T10:59:19.30963Z","end":"2026-03-31T10:59:19.617297Z","steps":["trace[790115343] 'read index received' (duration: 41.111818ms)","trace[790115343] 'applied index is now lower than readState.Index' (duration: 266.554219ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:19.617387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.75515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:59:19.617593Z","caller":"traceutil/trace.go:171","msg":"trace[741141042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1187; }","duration":"307.79892ms","start":"2026-03-31T10:59:19.309604Z","end":"2026-03-31T10:59:19.617402Z","steps":["trace[741141042] 'agreement among raft nodes before linearized reading' (duration: 307.745149ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:19.617586Z","caller":"traceutil/trace.go:171","msg":"trace[1384179377] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"371.826259ms","start":"2026-03-31T10:59:19.245545Z","end":"2026-03-31T10:59:19.617371Z","steps":["trace[1384179377] 'process raft request' (duration: 105.169616ms)","trace[1384179377] 'compare' (duration: 266.034205ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:19.617783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:19.245523Z","time spent":"372.169378ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:19.617632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:19.309584Z","time spent":"308.040428ms","remote":"127.0.0.1:36834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T10:59:19.656048Z","caller":"traceutil/trace.go:171","msg":"trace[625026180] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"342.956833ms","start":"2026-03-31T10:59:19.313046Z","end":"2026-03-31T10:59:19.656003Z","steps":["trace[625026180] 'process raft request' (duration: 342.622212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:19.656256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:19.313028Z","time spent":"343.118827ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:59:19.656027Z","caller":"traceutil/trace.go:171","msg":"trace[1021931620] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"342.913311ms","start":"2026-03-31T10:59:19.313077Z","end":"2026-03-31T10:59:19.65599Z","steps":["trace[1021931620] 'process raft request' (duration: 342.760028ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:19.656422Z","caller":"traceutil/trace.go:171","msg":"trace[1537035310] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"339.97098ms","start":"2026-03-31T10:59:19.316409Z","end":"2026-03-31T10:59:19.65638Z","steps":["trace[1537035310] 'process raft request' (duration: 339.860467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:19.656489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:19.313053Z","time spent":"343.365973ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:19.656531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:19.316399Z","time spent":"340.081983ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:59:19.656563Z","caller":"traceutil/trace.go:171","msg":"trace[328744794] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"343.323563ms","start":"2026-03-31T10:59:19.313189Z","end":"2026-03-31T10:59:19.656512Z","steps":["trace[328744794] 'process raft request' (duration: 342.721967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:19.656872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:19.313157Z","time spent":"343.505348ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:59:19.93763Z","caller":"traceutil/trace.go:171","msg":"trace[1139812156] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"223.833288ms","start":"2026-03-31T10:59:19.713755Z","end":"2026-03-31T10:59:19.937588Z","steps":["trace[1139812156] 'read index received' (duration: 149.306187ms)","trace[1139812156] 'applied index is now lower than readState.Index' (duration: 74.525021ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:19.93766Z","caller":"traceutil/trace.go:171","msg":"trace[501859130] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"229.902736ms","start":"2026-03-31T10:59:19.707702Z","end":"2026-03-31T10:59:19.937605Z","steps":["trace[501859130] 'process raft request' (duration: 155.334425ms)","trace[501859130] 'compare' (duration: 74.317654ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:19.937953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.165787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:1 size:1054"} {"level":"info","ts":"2026-03-31T10:59:19.938005Z","caller":"traceutil/trace.go:171","msg":"trace[2083925349] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:1; response_revision:1193; }","duration":"224.282711ms","start":"2026-03-31T10:59:19.713705Z","end":"2026-03-31T10:59:19.937988Z","steps":["trace[2083925349] 'agreement among raft nodes before linearized reading' (duration: 224.027544ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:20.176028Z","caller":"traceutil/trace.go:171","msg":"trace[800067196] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1195; }","duration":"168.001079ms","start":"2026-03-31T10:59:20.007997Z","end":"2026-03-31T10:59:20.175998Z","steps":["trace[800067196] 'process raft request' (duration: 98.093368ms)","trace[800067196] 'compare' (duration: 69.781168ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:20.3911Z","caller":"traceutil/trace.go:171","msg":"trace[1771039838] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1196; }","duration":"199.807918ms","start":"2026-03-31T10:59:20.191239Z","end":"2026-03-31T10:59:20.391047Z","steps":["trace[1771039838] 'process raft request' (duration: 116.195674ms)","trace[1771039838] 'compare' (duration: 83.211383ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:33.797597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.506746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T10:59:33.797691Z","caller":"traceutil/trace.go:171","msg":"trace[1747130408] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1305; }","duration":"507.64983ms","start":"2026-03-31T10:59:33.290022Z","end":"2026-03-31T10:59:33.797672Z","steps":["trace[1747130408] 'count revisions from in-memory index tree' (duration: 507.414844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:33.797732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:33.290002Z","time spent":"507.720331ms","remote":"127.0.0.1:36934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":18,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-03-31T10:59:33.79795Z","caller":"traceutil/trace.go:171","msg":"trace[1658280673] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"484.04119ms","start":"2026-03-31T10:59:33.313875Z","end":"2026-03-31T10:59:33.797916Z","steps":["trace[1658280673] 'read index received' (duration: 463.869567ms)","trace[1658280673] 'applied index is now lower than readState.Index' (duration: 20.170493ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:59:33.798355Z","caller":"traceutil/trace.go:171","msg":"trace[688451626] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"501.149639ms","start":"2026-03-31T10:59:33.297161Z","end":"2026-03-31T10:59:33.798311Z","steps":["trace[688451626] 'process raft request' (duration: 480.677638ms)","trace[688451626] 'compare' (duration: 19.848734ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:59:33.798508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:33.297138Z","time spent":"501.293482ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:59:33.798607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.556668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T10:59:33.798657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.73561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-dkf6j\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-03-31T10:59:33.798711Z","caller":"traceutil/trace.go:171","msg":"trace[1578679390] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1306; }","duration":"229.718712ms","start":"2026-03-31T10:59:33.568939Z","end":"2026-03-31T10:59:33.798657Z","steps":["trace[1578679390] 'agreement among raft nodes before linearized reading' (duration: 229.500886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:33.798735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.719399ms","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-31T10:59:33.798773Z","caller":"traceutil/trace.go:171","msg":"trace[1186319462] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-dkf6j; range_end:; response_count:1; response_revision:1306; }","duration":"484.911625ms","start":"2026-03-31T10:59:33.313834Z","end":"2026-03-31T10:59:33.798746Z","steps":["trace[1186319462] 'agreement among raft nodes before linearized reading' (duration: 484.522844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:59:33.798831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:59:33.313817Z","time spent":"485.005628ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":4220,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-dkf6j\" "} {"level":"info","ts":"2026-03-31T10:59:33.798874Z","caller":"traceutil/trace.go:171","msg":"trace[1450288038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1306; }","duration":"115.863764ms","start":"2026-03-31T10:59:33.682945Z","end":"2026-03-31T10:59:33.798809Z","steps":["trace[1450288038] 'agreement among raft nodes before linearized reading' (duration: 115.514564ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:59:39.275237Z","caller":"traceutil/trace.go:171","msg":"trace[890034311] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"125.312399ms","start":"2026-03-31T10:59:39.149893Z","end":"2026-03-31T10:59:39.275206Z","steps":["trace[890034311] 'process raft request' (duration: 125.163204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:11.95871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.444797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-31T11:00:11.959078Z","caller":"traceutil/trace.go:171","msg":"trace[1890054602] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1586; }","duration":"348.839457ms","start":"2026-03-31T11:00:11.610216Z","end":"2026-03-31T11:00:11.959055Z","steps":["trace[1890054602] 'range keys from in-memory index tree' (duration: 348.254321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:11.959131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:11.610206Z","time spent":"348.911429ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":869,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-03-31T11:00:11.958778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.513146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/local-path-storage/\" range_end:\"/registry/services/endpoints/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:00:11.959263Z","caller":"traceutil/trace.go:171","msg":"trace[727579673] range","detail":"{range_begin:/registry/services/endpoints/local-path-storage/; range_end:/registry/services/endpoints/local-path-storage0; response_count:0; response_revision:1586; }","duration":"341.04964ms","start":"2026-03-31T11:00:11.618179Z","end":"2026-03-31T11:00:11.959228Z","steps":["trace[727579673] 'range keys from in-memory index tree' (duration: 340.428763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:11.959329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:11.618159Z","time spent":"341.152773ms","remote":"127.0.0.1:36982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/local-path-storage/\" range_end:\"/registry/services/endpoints/local-path-storage0\" limit:500 "} {"level":"warn","ts":"2026-03-31T11:00:11.958816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.400531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/default\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-31T11:00:11.959421Z","caller":"traceutil/trace.go:171","msg":"trace[2013010081] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/default; range_end:; response_count:1; response_revision:1586; }","duration":"369.020888ms","start":"2026-03-31T11:00:11.590384Z","end":"2026-03-31T11:00:11.959405Z","steps":["trace[2013010081] 'range keys from in-memory index tree' (duration: 368.295939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:11.958964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.473949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/\" range_end:\"/registry/daemonsets/kube-system0\" limit:500 ","response":"range_response_count:2 size:17391"} {"level":"warn","ts":"2026-03-31T11:00:11.95948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:11.590377Z","time spent":"369.08913ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/default\" "} {"level":"info","ts":"2026-03-31T11:00:11.959522Z","caller":"traceutil/trace.go:171","msg":"trace[545076992] range","detail":"{range_begin:/registry/daemonsets/kube-system/; range_end:/registry/daemonsets/kube-system0; response_count:2; response_revision:1586; }","duration":"371.063095ms","start":"2026-03-31T11:00:11.588438Z","end":"2026-03-31T11:00:11.959501Z","steps":["trace[545076992] 'range keys from in-memory index tree' (duration: 370.261433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:11.959582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:11.588418Z","time spent":"371.144757ms","remote":"127.0.0.1:37290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":2,"response size":17414,"request content":"key:\"/registry/daemonsets/kube-system/\" range_end:\"/registry/daemonsets/kube-system0\" limit:500 "} {"level":"info","ts":"2026-03-31T11:00:30.249491Z","caller":"traceutil/trace.go:171","msg":"trace[1815054732] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"124.304129ms","start":"2026-03-31T11:00:30.125161Z","end":"2026-03-31T11:00:30.249465Z","steps":["trace[1815054732] 'process raft request' (duration: 124.146034ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:00:30.286965Z","caller":"traceutil/trace.go:171","msg":"trace[999995090] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1708; }","duration":"114.009083ms","start":"2026-03-31T11:00:30.172923Z","end":"2026-03-31T11:00:30.286932Z","steps":["trace[999995090] 'read index received' (duration: 77.226429ms)","trace[999995090] 'applied index is now lower than readState.Index' (duration: 36.781394ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:00:30.287021Z","caller":"traceutil/trace.go:171","msg":"trace[1021546311] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"115.527755ms","start":"2026-03-31T11:00:30.171466Z","end":"2026-03-31T11:00:30.286994Z","steps":["trace[1021546311] 'process raft request' (duration: 115.312379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:30.287373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.405433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:00:30.287526Z","caller":"traceutil/trace.go:171","msg":"trace[1196615524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1651; }","duration":"114.618628ms","start":"2026-03-31T11:00:30.172879Z","end":"2026-03-31T11:00:30.287497Z","steps":["trace[1196615524] 'agreement among raft nodes before linearized reading' (duration: 114.174336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:30.305164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.810161ms","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-31T11:00:30.305225Z","caller":"traceutil/trace.go:171","msg":"trace[167898358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1652; }","duration":"120.922975ms","start":"2026-03-31T11:00:30.184285Z","end":"2026-03-31T11:00:30.305208Z","steps":["trace[167898358] 'agreement among raft nodes before linearized reading' (duration: 120.7483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:30.305326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.578842ms","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-31T11:00:30.305411Z","caller":"traceutil/trace.go:171","msg":"trace[1857406656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1652; }","duration":"112.688055ms","start":"2026-03-31T11:00:30.192698Z","end":"2026-03-31T11:00:30.305386Z","steps":["trace[1857406656] 'agreement among raft nodes before linearized reading' (duration: 112.496359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:30.30541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.822224ms","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-31T11:00:30.305473Z","caller":"traceutil/trace.go:171","msg":"trace[247488965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1652; }","duration":"119.909687ms","start":"2026-03-31T11:00:30.185545Z","end":"2026-03-31T11:00:30.305455Z","steps":["trace[247488965] 'agreement among raft nodes before linearized reading' (duration: 119.747762ms)"],"step_count":1}