{"level":"warn","ts":"2026-03-14T01:29:54.749897Z","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-14T01:29:54.750062Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110: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-14T01:29:54.750164Z","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-14T01:29:54.750199Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-14T01:29:54.750227Z","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-14T01:29:54.750925Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-03-14T01:29:54.751077Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110: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-14T01:29:54.759573Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.219976ms"} {"level":"info","ts":"2026-03-14T01:29:54.767963Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-03-14T01:29:54.768139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T01:29:54.768206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-03-14T01:29:54.768244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T01:29:54.768298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-03-14T01:29:54.768391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-03-14T01:29:54.776852Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T01:29:54.780789Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T01:29:54.782649Z","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-14T01:29:54.785173Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T01:29:54.785421Z","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-14T01:29:54.785514Z","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-14T01:29:54.785563Z","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-14T01:29:54.785812Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T01:29:54.786501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-03-14T01:29:54.786638Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-14T01:29:54.788515Z","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-14T01:29:54.788623Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-14T01:29:54.788658Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-14T01:29:54.788897Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T01:29:54.788951Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T01:29:55.369694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T01:29:55.369856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T01:29:55.369895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-03-14T01:29:55.369929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-03-14T01:29:55.36995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-14T01:29:55.369973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-03-14T01:29:55.369999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-14T01:29:55.371104Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:29:55.372334Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T01:29:55.372373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:29:55.372474Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:29:55.374134Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-03-14T01:29:55.374498Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T01:29:55.374573Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T01:29:55.381924Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:29:55.382051Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:29:55.382078Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:29:55.382704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T01:30:19.841161Z","caller":"traceutil/trace.go:171","msg":"trace[1663931069] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"319.971464ms","start":"2026-03-14T01:30:19.521148Z","end":"2026-03-14T01:30:19.841119Z","steps":["trace[1663931069] 'process raft request' (duration: 319.739339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:19.841971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:19.521126Z","time spent":"320.169748ms","remote":"127.0.0.1:55086","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-14T01:30:20.065818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.72593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:30:20.065929Z","caller":"traceutil/trace.go:171","msg":"trace[1630005323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"134.904245ms","start":"2026-03-14T01:30:19.931009Z","end":"2026-03-14T01:30:20.065913Z","steps":["trace[1630005323] 'range keys from in-memory index tree' (duration: 134.651279ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:25.276659Z","caller":"traceutil/trace.go:171","msg":"trace[1621142340] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"260.081391ms","start":"2026-03-14T01:30:25.016553Z","end":"2026-03-14T01:30:25.276634Z","steps":["trace[1621142340] 'process raft request' (duration: 259.868566ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:27.686391Z","caller":"traceutil/trace.go:171","msg":"trace[1854570934] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"402.218141ms","start":"2026-03-14T01:30:27.284146Z","end":"2026-03-14T01:30:27.686364Z","steps":["trace[1854570934] 'process raft request' (duration: 402.084719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:27.686549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:27.284126Z","time spent":"402.336595ms","remote":"127.0.0.1:55086","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-14T01:30:28.683569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.090843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:30:28.683681Z","caller":"traceutil/trace.go:171","msg":"trace[1138044492] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"811.282753ms","start":"2026-03-14T01:30:27.872382Z","end":"2026-03-14T01:30:28.683665Z","steps":["trace[1138044492] 'read index received' (duration: 135.807693ms)","trace[1138044492] 'applied index is now lower than readState.Index' (duration: 675.47394ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:30:28.683757Z","caller":"traceutil/trace.go:171","msg":"trace[991609349] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.212462903s","start":"2026-03-14T01:30:27.471283Z","end":"2026-03-14T01:30:28.683746Z","steps":["trace[991609349] 'process raft request' (duration: 536.960392ms)","trace[991609349] 'compare' (duration: 674.99811ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:30:28.683815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:27.471257Z","time spent":"1.212529535s","remote":"127.0.0.1:55086","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-14T01:30:28.683951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.576979ms","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-14T01:30:28.683983Z","caller":"traceutil/trace.go:171","msg":"trace[523388240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"811.647181ms","start":"2026-03-14T01:30:27.872328Z","end":"2026-03-14T01:30:28.683975Z","steps":["trace[523388240] 'agreement among raft nodes before linearized reading' (duration: 811.587249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:28.684016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:27.872305Z","time spent":"811.702201ms","remote":"127.0.0.1:55086","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-14T01:30:28.68416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.4175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T01:30:28.684193Z","caller":"traceutil/trace.go:171","msg":"trace[1451179202] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:517; }","duration":"343.474371ms","start":"2026-03-14T01:30:28.340711Z","end":"2026-03-14T01:30:28.684185Z","steps":["trace[1451179202] 'agreement among raft nodes before linearized reading' (duration: 343.41844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:28.68422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:28.340697Z","time spent":"343.517672ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"warn","ts":"2026-03-14T01:30:28.684359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.166802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-14T01:30:28.684414Z","caller":"traceutil/trace.go:171","msg":"trace[1940838758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"332.222323ms","start":"2026-03-14T01:30:28.352177Z","end":"2026-03-14T01:30:28.684399Z","steps":["trace[1940838758] 'agreement among raft nodes before linearized reading' (duration: 332.050529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:28.684448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:28.352085Z","time spent":"332.355546ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-14T01:30:28.684543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.474634ms","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-14T01:30:28.684679Z","caller":"traceutil/trace.go:171","msg":"trace[29905514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"315.707628ms","start":"2026-03-14T01:30:28.368904Z","end":"2026-03-14T01:30:28.684612Z","steps":["trace[29905514] 'agreement among raft nodes before linearized reading' (duration: 315.234209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:28.684787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:28.368894Z","time spent":"315.879872ms","remote":"127.0.0.1:55086","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-14T01:30:28.930765Z","caller":"traceutil/trace.go:171","msg":"trace[1613269180] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"241.912154ms","start":"2026-03-14T01:30:28.688823Z","end":"2026-03-14T01:30:28.930735Z","steps":["trace[1613269180] 'process raft request' (duration: 241.649459ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:28.931359Z","caller":"traceutil/trace.go:171","msg":"trace[2146449727] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"242.395323ms","start":"2026-03-14T01:30:28.688933Z","end":"2026-03-14T01:30:28.931329Z","steps":["trace[2146449727] 'process raft request' (duration: 241.726469ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:28.931492Z","caller":"traceutil/trace.go:171","msg":"trace[2146977089] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"241.77155ms","start":"2026-03-14T01:30:28.689705Z","end":"2026-03-14T01:30:28.931477Z","steps":["trace[2146977089] 'process raft request' (duration: 241.723679ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:35.501493Z","caller":"traceutil/trace.go:171","msg":"trace[1492482695] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"521.297035ms","start":"2026-03-14T01:30:34.980156Z","end":"2026-03-14T01:30:35.501453Z","steps":["trace[1492482695] 'process raft request' (duration: 521.143892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:35.50168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:34.980141Z","time spent":"521.430767ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:30:35.536014Z","caller":"traceutil/trace.go:171","msg":"trace[1217735814] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"554.641761ms","start":"2026-03-14T01:30:34.981346Z","end":"2026-03-14T01:30:35.535988Z","steps":["trace[1217735814] 'process raft request' (duration: 554.436947ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:35.536154Z","caller":"traceutil/trace.go:171","msg":"trace[1238241580] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:551; }","duration":"491.312438ms","start":"2026-03-14T01:30:35.044825Z","end":"2026-03-14T01:30:35.536138Z","steps":["trace[1238241580] 'read index received' (duration: 456.510733ms)","trace[1238241580] 'applied index is now lower than readState.Index' (duration: 34.800385ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:30:35.536186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:34.981338Z","time spent":"554.745503ms","remote":"127.0.0.1:55086","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-14T01:30:35.536233Z","caller":"traceutil/trace.go:171","msg":"trace[890491558] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"554.002139ms","start":"2026-03-14T01:30:34.982214Z","end":"2026-03-14T01:30:35.536216Z","steps":["trace[890491558] 'process raft request' (duration: 553.740903ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:35.536305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.478921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tpqjp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-14T01:30:35.536339Z","caller":"traceutil/trace.go:171","msg":"trace[376690604] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tpqjp; range_end:; response_count:1; response_revision:537; }","duration":"491.551823ms","start":"2026-03-14T01:30:35.044776Z","end":"2026-03-14T01:30:35.536328Z","steps":["trace[376690604] 'agreement among raft nodes before linearized reading' (duration: 491.42152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:35.536372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:35.044758Z","time spent":"491.606614ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-tpqjp\" "} {"level":"warn","ts":"2026-03-14T01:30:35.53632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:34.982208Z","time spent":"554.06706ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:30:35.694734Z","caller":"traceutil/trace.go:171","msg":"trace[357247758] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"141.186722ms","start":"2026-03-14T01:30:35.553528Z","end":"2026-03-14T01:30:35.694715Z","steps":["trace[357247758] 'process raft request' (duration: 106.237013ms)","trace[357247758] 'compare' (duration: 34.852507ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:30:37.359379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427684836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:30:37.359458Z","caller":"traceutil/trace.go:171","msg":"trace[1505086009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"1.427806548s","start":"2026-03-14T01:30:35.931633Z","end":"2026-03-14T01:30:37.35944Z","steps":["trace[1505086009] 'range keys from in-memory index tree' (duration: 1.427605904s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:37.359499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:35.931614Z","time spent":"1.427874299s","remote":"127.0.0.1:54816","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-14T01:30:37.882424Z","caller":"traceutil/trace.go:171","msg":"trace[1991793390] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:557; }","duration":"107.465568ms","start":"2026-03-14T01:30:37.774942Z","end":"2026-03-14T01:30:37.882408Z","steps":["trace[1991793390] 'read index received' (duration: 40.344127ms)","trace[1991793390] 'applied index is now lower than readState.Index' (duration: 67.120841ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:30:37.882452Z","caller":"traceutil/trace.go:171","msg":"trace[2052804650] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"325.61029ms","start":"2026-03-14T01:30:37.556815Z","end":"2026-03-14T01:30:37.882426Z","steps":["trace[2052804650] 'process raft request' (duration: 258.464609ms)","trace[2052804650] 'compare' (duration: 66.928807ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:30:37.88249Z","caller":"traceutil/trace.go:171","msg":"trace[167229155] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"324.263623ms","start":"2026-03-14T01:30:37.558201Z","end":"2026-03-14T01:30:37.882465Z","steps":["trace[167229155] 'process raft request' (duration: 324.153921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:37.882597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:37.556791Z","time spent":"325.705731ms","remote":"127.0.0.1:55086","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-14T01:30:37.88264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.696293ms","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-14T01:30:37.882706Z","caller":"traceutil/trace.go:171","msg":"trace[744515374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"107.811684ms","start":"2026-03-14T01:30:37.774883Z","end":"2026-03-14T01:30:37.882694Z","steps":["trace[744515374] 'agreement among raft nodes before linearized reading' (duration: 107.58866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:37.882868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:37.558179Z","time spent":"324.575588ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:30:39.566854Z","caller":"traceutil/trace.go:171","msg":"trace[985390712] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"381.436571ms","start":"2026-03-14T01:30:39.185385Z","end":"2026-03-14T01:30:39.566822Z","steps":["trace[985390712] 'process raft request' (duration: 381.156986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:39.567067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:39.185373Z","time spent":"381.584414ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:30:39.571685Z","caller":"traceutil/trace.go:171","msg":"trace[816665581] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"385.516973ms","start":"2026-03-14T01:30:39.186155Z","end":"2026-03-14T01:30:39.571672Z","steps":["trace[816665581] 'process raft request' (duration: 385.052304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:39.571772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:39.186145Z","time spent":"385.583914ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:30:39.572143Z","caller":"traceutil/trace.go:171","msg":"trace[1988759926] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:587; }","duration":"231.001202ms","start":"2026-03-14T01:30:39.34057Z","end":"2026-03-14T01:30:39.571571Z","steps":["trace[1988759926] 'read index received' (duration: 225.883358ms)","trace[1988759926] 'applied index is now lower than readState.Index' (duration: 5.116814ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:30:39.572366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.780768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:30:39.572429Z","caller":"traceutil/trace.go:171","msg":"trace[964982781] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:571; }","duration":"231.88764ms","start":"2026-03-14T01:30:39.340521Z","end":"2026-03-14T01:30:39.572408Z","steps":["trace[964982781] 'agreement among raft nodes before linearized reading' (duration: 231.746367ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:47.496828Z","caller":"traceutil/trace.go:171","msg":"trace[898256416] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"155.215025ms","start":"2026-03-14T01:30:47.341595Z","end":"2026-03-14T01:30:47.49681Z","steps":["trace[898256416] 'process raft request' (duration: 155.088663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:47.805597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.057864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tpqjp\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-03-14T01:30:47.80566Z","caller":"traceutil/trace.go:171","msg":"trace[874317543] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tpqjp; range_end:; response_count:1; response_revision:658; }","duration":"125.166227ms","start":"2026-03-14T01:30:47.680482Z","end":"2026-03-14T01:30:47.805648Z","steps":["trace[874317543] 'range keys from in-memory index tree' (duration: 124.918631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:47.805634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.309798ms","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-14T01:30:47.805716Z","caller":"traceutil/trace.go:171","msg":"trace[157624967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:658; }","duration":"196.429011ms","start":"2026-03-14T01:30:47.609268Z","end":"2026-03-14T01:30:47.805697Z","steps":["trace[157624967] 'range keys from in-memory index tree' (duration: 196.092914ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:53.687144Z","caller":"traceutil/trace.go:171","msg":"trace[283376303] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"480.141483ms","start":"2026-03-14T01:30:53.206979Z","end":"2026-03-14T01:30:53.68712Z","steps":["trace[283376303] 'process raft request' (duration: 479.974199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:53.687305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:53.206958Z","time spent":"480.273425ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:30:54.880795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150721486s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-14T01:30:54.880945Z","caller":"traceutil/trace.go:171","msg":"trace[1197639068] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"1.044557909s","start":"2026-03-14T01:30:53.836372Z","end":"2026-03-14T01:30:54.88093Z","steps":["trace[1197639068] 'read index received' (duration: 32.79µs)","trace[1197639068] 'applied index is now lower than readState.Index' (duration: 1.044524329s)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:30:54.88127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044889097s","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-14T01:30:54.881301Z","caller":"traceutil/trace.go:171","msg":"trace[1273803741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:722; }","duration":"1.044964658s","start":"2026-03-14T01:30:53.836329Z","end":"2026-03-14T01:30:54.881294Z","steps":["trace[1273803741] 'agreement among raft nodes before linearized reading' (duration: 1.044841984s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:54.881324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:53.836313Z","time spent":"1.045006348s","remote":"127.0.0.1:55086","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-14T01:30:54.881234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.194894ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:30:54.881429Z","caller":"traceutil/trace.go:171","msg":"trace[433517121] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:722; }","duration":"280.42759ms","start":"2026-03-14T01:30:54.600974Z","end":"2026-03-14T01:30:54.881402Z","steps":["trace[433517121] 'range keys from in-memory index tree' (duration: 280.160963ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:54.88256Z","caller":"traceutil/trace.go:171","msg":"trace[1770696175] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"725.872015ms","start":"2026-03-14T01:30:54.156666Z","end":"2026-03-14T01:30:54.882538Z","steps":["trace[1770696175] 'process raft request' (duration: 724.212583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:54.882644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:54.156633Z","time spent":"725.965816ms","remote":"127.0.0.1:54892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T01:30:54.884468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.337648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:30:54.884516Z","caller":"traceutil/trace.go:171","msg":"trace[1557992494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"952.4552ms","start":"2026-03-14T01:30:53.932052Z","end":"2026-03-14T01:30:54.884507Z","steps":["trace[1557992494] 'agreement among raft nodes before linearized reading' (duration: 950.49467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:54.884546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:53.932034Z","time spent":"952.507381ms","remote":"127.0.0.1:54816","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-14T01:30:54.884495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.417924ms","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-14T01:30:54.884591Z","caller":"traceutil/trace.go:171","msg":"trace[1913030013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:723; }","duration":"933.557427ms","start":"2026-03-14T01:30:53.951018Z","end":"2026-03-14T01:30:54.884576Z","steps":["trace[1913030013] 'agreement among raft nodes before linearized reading' (duration: 931.562387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:54.88465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:53.950991Z","time spent":"933.647819ms","remote":"127.0.0.1:55086","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-14T01:30:54.884667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.467595ms","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-14T01:30:54.884784Z","caller":"traceutil/trace.go:171","msg":"trace[1451509716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:723; }","duration":"933.658889ms","start":"2026-03-14T01:30:53.951098Z","end":"2026-03-14T01:30:54.884757Z","steps":["trace[1451509716] 'agreement among raft nodes before linearized reading' (duration: 931.474025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:54.884862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:53.951058Z","time spent":"933.787251ms","remote":"127.0.0.1:55086","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-14T01:30:54.886168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.737713ms","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-14T01:30:54.886244Z","caller":"traceutil/trace.go:171","msg":"trace[1332360103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:723; }","duration":"921.833245ms","start":"2026-03-14T01:30:53.964387Z","end":"2026-03-14T01:30:54.88622Z","steps":["trace[1332360103] 'agreement among raft nodes before linearized reading' (duration: 918.178283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:54.886568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:53.964365Z","time spent":"922.181771ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-14T01:30:57.400786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645045063472659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T01:30:57.901478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645045063472659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T01:30:58.235909Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.340762788s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T01:30:58.236292Z","caller":"traceutil/trace.go:171","msg":"trace[1386446417] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"1.335902532s","start":"2026-03-14T01:30:56.900369Z","end":"2026-03-14T01:30:58.236272Z","steps":["trace[1386446417] 'read index received' (duration: 1.335651777s)","trace[1386446417] 'applied index is now lower than readState.Index' (duration: 250.045µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:30:58.236374Z","caller":"traceutil/trace.go:171","msg":"trace[1091058132] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"1.341261608s","start":"2026-03-14T01:30:56.895076Z","end":"2026-03-14T01:30:58.236338Z","steps":["trace[1091058132] 'process raft request' (duration: 1.341036544s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:58.23653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:56.895065Z","time spent":"1.341381231s","remote":"127.0.0.1:55086","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-14T01:30:58.236537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.868143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-14T01:30:58.236559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.34149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:30:58.236566Z","caller":"traceutil/trace.go:171","msg":"trace[2036056259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:729; }","duration":"535.941155ms","start":"2026-03-14T01:30:57.700617Z","end":"2026-03-14T01:30:58.236558Z","steps":["trace[2036056259] 'agreement among raft nodes before linearized reading' (duration: 535.851313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:58.236561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006841004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T01:30:58.236589Z","caller":"traceutil/trace.go:171","msg":"trace[1356126738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"304.409772ms","start":"2026-03-14T01:30:57.932167Z","end":"2026-03-14T01:30:58.236577Z","steps":["trace[1356126738] 'agreement among raft nodes before linearized reading' (duration: 304.350621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:58.236593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:57.700601Z","time spent":"535.985845ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T01:30:58.236616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:57.932149Z","time spent":"304.463223ms","remote":"127.0.0.1:54816","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-14T01:30:58.236619Z","caller":"traceutil/trace.go:171","msg":"trace[2025332312] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:729; }","duration":"1.006960537s","start":"2026-03-14T01:30:57.229645Z","end":"2026-03-14T01:30:58.236605Z","steps":["trace[2025332312] 'agreement among raft nodes before linearized reading' (duration: 1.006835154s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:58.236647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330174259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-14T01:30:58.236657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:57.229624Z","time spent":"1.007025547s","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:30:58.236689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33633006s","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-14T01:30:58.236696Z","caller":"traceutil/trace.go:171","msg":"trace[407946972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:729; }","duration":"1.330237091s","start":"2026-03-14T01:30:56.906445Z","end":"2026-03-14T01:30:58.236682Z","steps":["trace[407946972] 'agreement among raft nodes before linearized reading' (duration: 1.330066428s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:58.236725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:56.906438Z","time spent":"1.330281482s","remote":"127.0.0.1:55086","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-14T01:30:58.236709Z","caller":"traceutil/trace.go:171","msg":"trace[1250865804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:729; }","duration":"1.336386201s","start":"2026-03-14T01:30:56.900316Z","end":"2026-03-14T01:30:58.236703Z","steps":["trace[1250865804] 'agreement among raft nodes before linearized reading' (duration: 1.336058205s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:58.236969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:56.900289Z","time spent":"1.336673467s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-14T01:30:58.237053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330918234s","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-14T01:30:58.237077Z","caller":"traceutil/trace.go:171","msg":"trace[1182635428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"1.330962144s","start":"2026-03-14T01:30:56.906108Z","end":"2026-03-14T01:30:58.23707Z","steps":["trace[1182635428] 'agreement among raft nodes before linearized reading' (duration: 1.330354823s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:58.237095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:56.906098Z","time spent":"1.330992605s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-14T01:30:58.702187Z","caller":"traceutil/trace.go:171","msg":"trace[807630769] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"461.936372ms","start":"2026-03-14T01:30:58.24022Z","end":"2026-03-14T01:30:58.702156Z","steps":["trace[807630769] 'process raft request' (duration: 417.695469ms)","trace[807630769] 'compare' (duration: 43.959547ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:30:58.702305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:58.240206Z","time spent":"462.035204ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:30:58.778197Z","caller":"traceutil/trace.go:171","msg":"trace[1396418746] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"537.23354ms","start":"2026-03-14T01:30:58.240935Z","end":"2026-03-14T01:30:58.778169Z","steps":["trace[1396418746] 'process raft request' (duration: 537.171738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:58.778337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:58.240927Z","time spent":"537.343432ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:30:58.778467Z","caller":"traceutil/trace.go:171","msg":"trace[2052040566] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:753; }","duration":"437.386508ms","start":"2026-03-14T01:30:58.341046Z","end":"2026-03-14T01:30:58.778432Z","steps":["trace[2052040566] 'read index received' (duration: 316.849047ms)","trace[2052040566] 'applied index is now lower than readState.Index' (duration: 120.536091ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:30:58.778601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.54399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T01:30:58.77859Z","caller":"traceutil/trace.go:171","msg":"trace[1836795724] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"538.073826ms","start":"2026-03-14T01:30:58.240483Z","end":"2026-03-14T01:30:58.778557Z","steps":["trace[1836795724] 'process raft request' (duration: 537.490545ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:58.778666Z","caller":"traceutil/trace.go:171","msg":"trace[1014372646] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"537.554276ms","start":"2026-03-14T01:30:58.241103Z","end":"2026-03-14T01:30:58.778657Z","steps":["trace[1014372646] 'process raft request' (duration: 537.037066ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:58.778633Z","caller":"traceutil/trace.go:171","msg":"trace[1474476847] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:733; }","duration":"437.611821ms","start":"2026-03-14T01:30:58.341012Z","end":"2026-03-14T01:30:58.778624Z","steps":["trace[1474476847] 'agreement among raft nodes before linearized reading' (duration: 437.51744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:58.778753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:58.241082Z","time spent":"537.610487ms","remote":"127.0.0.1:55086","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-14T01:30:58.778753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:58.240472Z","time spent":"538.195158ms","remote":"127.0.0.1:55086","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-14T01:30:58.77879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:58.341001Z","time spent":"437.777775ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"info","ts":"2026-03-14T01:31:17.077708Z","caller":"traceutil/trace.go:171","msg":"trace[1051065612] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"231.204097ms","start":"2026-03-14T01:31:16.846483Z","end":"2026-03-14T01:31:17.077687Z","steps":["trace[1051065612] 'read index received' (duration: 231.018753ms)","trace[1051065612] 'applied index is now lower than readState.Index' (duration: 184.364µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:17.077836Z","caller":"traceutil/trace.go:171","msg":"trace[259771693] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"294.875963ms","start":"2026-03-14T01:31:16.782926Z","end":"2026-03-14T01:31:17.077802Z","steps":["trace[259771693] 'process raft request' (duration: 294.634899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:17.077931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.436591ms","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-14T01:31:17.077995Z","caller":"traceutil/trace.go:171","msg":"trace[1484100071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"231.558064ms","start":"2026-03-14T01:31:16.846423Z","end":"2026-03-14T01:31:17.077981Z","steps":["trace[1484100071] 'agreement among raft nodes before linearized reading' (duration: 231.386091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:17.078185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.947482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:31:17.078228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.275297ms","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-14T01:31:17.078255Z","caller":"traceutil/trace.go:171","msg":"trace[588262319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"146.039664ms","start":"2026-03-14T01:31:16.932194Z","end":"2026-03-14T01:31:17.078234Z","steps":["trace[588262319] 'agreement among raft nodes before linearized reading' (duration: 145.924651ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:17.078285Z","caller":"traceutil/trace.go:171","msg":"trace[969581846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"230.37779ms","start":"2026-03-14T01:31:16.847891Z","end":"2026-03-14T01:31:17.078269Z","steps":["trace[969581846] 'agreement among raft nodes before linearized reading' (duration: 230.175956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:17.07848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.663636ms","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-14T01:31:17.078557Z","caller":"traceutil/trace.go:171","msg":"trace[256650228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"229.783247ms","start":"2026-03-14T01:31:16.848757Z","end":"2026-03-14T01:31:17.07854Z","steps":["trace[256650228] 'agreement among raft nodes before linearized reading' (duration: 229.473312ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:18.600552Z","caller":"traceutil/trace.go:171","msg":"trace[1507011453] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"190.442801ms","start":"2026-03-14T01:31:18.410085Z","end":"2026-03-14T01:31:18.600528Z","steps":["trace[1507011453] 'read index received' (duration: 190.304999ms)","trace[1507011453] 'applied index is now lower than readState.Index' (duration: 136.302µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:18.60087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.775067ms","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-14T01:31:18.600906Z","caller":"traceutil/trace.go:171","msg":"trace[319078252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"190.87452ms","start":"2026-03-14T01:31:18.410023Z","end":"2026-03-14T01:31:18.600898Z","steps":["trace[319078252] 'agreement among raft nodes before linearized reading' (duration: 190.597784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:19.313586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.748992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:31:19.313781Z","caller":"traceutil/trace.go:171","msg":"trace[1241098862] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:820; }","duration":"380.7968ms","start":"2026-03-14T01:31:18.932967Z","end":"2026-03-14T01:31:19.313764Z","steps":["trace[1241098862] 'read index received' (duration: 53.803852ms)","trace[1241098862] 'applied index is now lower than readState.Index' (duration: 326.991828ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:19.313862Z","caller":"traceutil/trace.go:171","msg":"trace[326564361] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"712.287027ms","start":"2026-03-14T01:31:18.601565Z","end":"2026-03-14T01:31:19.313852Z","steps":["trace[326564361] 'process raft request' (duration: 385.196747ms)","trace[326564361] 'compare' (duration: 326.641721ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:19.313921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:18.601546Z","time spent":"712.344008ms","remote":"127.0.0.1:54860","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-14T01:31:19.314174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.210318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:19.314155Z","caller":"traceutil/trace.go:171","msg":"trace[1187336039] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"709.609914ms","start":"2026-03-14T01:31:18.604517Z","end":"2026-03-14T01:31:19.314127Z","steps":["trace[1187336039] 'process raft request' (duration: 709.165876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:19.314265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.269973ms","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-14T01:31:19.314229Z","caller":"traceutil/trace.go:171","msg":"trace[940845420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"381.30267ms","start":"2026-03-14T01:31:18.932913Z","end":"2026-03-14T01:31:19.314215Z","steps":["trace[940845420] 'agreement among raft nodes before linearized reading' (duration: 381.169487ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:19.314314Z","caller":"traceutil/trace.go:171","msg":"trace[330133772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"210.333624ms","start":"2026-03-14T01:31:19.103968Z","end":"2026-03-14T01:31:19.314302Z","steps":["trace[330133772] 'agreement among raft nodes before linearized reading' (duration: 210.251562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:19.314329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:18.604505Z","time spent":"709.726726ms","remote":"127.0.0.1:55086","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-14T01:31:19.314339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.953825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-14T01:31:19.314401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:18.932892Z","time spent":"381.417782ms","remote":"127.0.0.1:54816","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-14T01:31:19.314199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.946171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-14T01:31:19.314507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.996315ms","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-14T01:31:19.314872Z","caller":"traceutil/trace.go:171","msg":"trace[524629199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"179.446904ms","start":"2026-03-14T01:31:19.135407Z","end":"2026-03-14T01:31:19.314854Z","steps":["trace[524629199] 'agreement among raft nodes before linearized reading' (duration: 178.757361ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:19.314619Z","caller":"traceutil/trace.go:171","msg":"trace[245490194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"231.391259ms","start":"2026-03-14T01:31:19.083199Z","end":"2026-03-14T01:31:19.31459Z","steps":["trace[245490194] 'agreement among raft nodes before linearized reading' (duration: 230.89105ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:19.314427Z","caller":"traceutil/trace.go:171","msg":"trace[93373273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"178.061407ms","start":"2026-03-14T01:31:19.136347Z","end":"2026-03-14T01:31:19.314408Z","steps":["trace[93373273] 'agreement among raft nodes before linearized reading' (duration: 177.752871ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:19.7129Z","caller":"traceutil/trace.go:171","msg":"trace[550276986] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"393.692205ms","start":"2026-03-14T01:31:19.31918Z","end":"2026-03-14T01:31:19.712872Z","steps":["trace[550276986] 'process raft request' (duration: 369.6095ms)","trace[550276986] 'compare' (duration: 23.960693ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:19.713085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:19.319165Z","time spent":"393.834968ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:31:19.770636Z","caller":"traceutil/trace.go:171","msg":"trace[1780270702] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"450.474016ms","start":"2026-03-14T01:31:19.320138Z","end":"2026-03-14T01:31:19.770612Z","steps":["trace[1780270702] 'process raft request' (duration: 450.397725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:19.770959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:19.32012Z","time spent":"450.583529ms","remote":"127.0.0.1:55086","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-14T01:31:19.770998Z","caller":"traceutil/trace.go:171","msg":"trace[734658055] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"445.834594ms","start":"2026-03-14T01:31:19.32514Z","end":"2026-03-14T01:31:19.770974Z","steps":["trace[734658055] 'process raft request' (duration: 445.436747ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:19.771176Z","caller":"traceutil/trace.go:171","msg":"trace[1777877553] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:822; }","duration":"446.475438ms","start":"2026-03-14T01:31:19.324685Z","end":"2026-03-14T01:31:19.77116Z","steps":["trace[1777877553] 'read index received' (duration: 364.133103ms)","trace[1777877553] 'applied index is now lower than readState.Index' (duration: 82.341455ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:19.771214Z","caller":"traceutil/trace.go:171","msg":"trace[1618380186] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"312.189086ms","start":"2026-03-14T01:31:19.459009Z","end":"2026-03-14T01:31:19.771199Z","steps":["trace[1618380186] 'process raft request' (duration: 311.854099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:19.771309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.60551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-14T01:31:19.771335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:19.458959Z","time spent":"312.287217ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:31:19.771349Z","caller":"traceutil/trace.go:171","msg":"trace[710197717] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:798; }","duration":"446.681621ms","start":"2026-03-14T01:31:19.324656Z","end":"2026-03-14T01:31:19.771338Z","steps":["trace[710197717] 'agreement among raft nodes before linearized reading' (duration: 446.587869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:19.77139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:19.324646Z","time spent":"446.735942ms","remote":"127.0.0.1:55102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-03-14T01:31:19.770921Z","caller":"traceutil/trace.go:171","msg":"trace[1354630302] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"450.848594ms","start":"2026-03-14T01:31:19.320042Z","end":"2026-03-14T01:31:19.77089Z","steps":["trace[1354630302] 'process raft request' (duration: 450.375505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:19.771687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:19.325128Z","time spent":"445.970347ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:31:19.771818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:19.320017Z","time spent":"451.740392ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:31:32.289025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645045063473038,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T01:31:32.789607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645045063473038,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T01:31:33.290548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645045063473038,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T01:31:33.376617Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.010923045s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T01:31:33.377076Z","caller":"traceutil/trace.go:171","msg":"trace[1215360500] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"2.011682429s","start":"2026-03-14T01:31:31.365368Z","end":"2026-03-14T01:31:33.377051Z","steps":["trace[1215360500] 'process raft request' (duration: 2.011519146s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:33.377245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:31.365345Z","time spent":"2.011817351s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:31:33.378265Z","caller":"traceutil/trace.go:171","msg":"trace[2057214429] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:911; }","duration":"1.589416879s","start":"2026-03-14T01:31:31.78881Z","end":"2026-03-14T01:31:33.378227Z","steps":["trace[2057214429] 'read index received' (duration: 1.588098723s)","trace[2057214429] 'applied index is now lower than readState.Index' (duration: 1.316636ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:33.378353Z","caller":"traceutil/trace.go:171","msg":"trace[155295623] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"1.119824835s","start":"2026-03-14T01:31:32.258503Z","end":"2026-03-14T01:31:33.378328Z","steps":["trace[155295623] 'process raft request' (duration: 1.119484659s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:33.378491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:32.258479Z","time spent":"1.119941688s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:31:33.378621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589776026s","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-14T01:31:33.378664Z","caller":"traceutil/trace.go:171","msg":"trace[764091932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:879; }","duration":"1.589887988s","start":"2026-03-14T01:31:31.788761Z","end":"2026-03-14T01:31:33.378649Z","steps":["trace[764091932] 'agreement among raft nodes before linearized reading' (duration: 1.589686575s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:33.378706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:31.788741Z","time spent":"1.58995699s","remote":"127.0.0.1:55086","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-14T01:31:33.378705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.555715634s","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-14T01:31:33.378777Z","caller":"traceutil/trace.go:171","msg":"trace[998159343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:879; }","duration":"1.555828446s","start":"2026-03-14T01:31:31.822931Z","end":"2026-03-14T01:31:33.378759Z","steps":["trace[998159343] 'agreement among raft nodes before linearized reading' (duration: 1.555588371s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:33.378796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550161784s","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-14T01:31:33.378825Z","caller":"traceutil/trace.go:171","msg":"trace[197377672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:879; }","duration":"1.550205465s","start":"2026-03-14T01:31:31.828609Z","end":"2026-03-14T01:31:33.378815Z","steps":["trace[197377672] 'agreement among raft nodes before linearized reading' (duration: 1.550069342s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:33.378826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:31.822915Z","time spent":"1.555896898s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T01:31:33.378861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447497886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:33.378875Z","caller":"traceutil/trace.go:171","msg":"trace[2020966942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"1.447540268s","start":"2026-03-14T01:31:31.931331Z","end":"2026-03-14T01:31:33.378871Z","steps":["trace[2020966942] 'agreement among raft nodes before linearized reading' (duration: 1.447518507s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:33.37889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:31.931314Z","time spent":"1.447573338s","remote":"127.0.0.1:54816","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-14T01:31:33.37884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:31.828601Z","time spent":"1.550236095s","remote":"127.0.0.1:55086","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-14T01:31:33.379127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.551982999s","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-14T01:31:33.379163Z","caller":"traceutil/trace.go:171","msg":"trace[1402839716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:879; }","duration":"1.552049731s","start":"2026-03-14T01:31:31.827103Z","end":"2026-03-14T01:31:33.379152Z","steps":["trace[1402839716] 'agreement among raft nodes before linearized reading' (duration: 1.551928219s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:33.379192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:31.827086Z","time spent":"1.552099582s","remote":"127.0.0.1:55086","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-14T01:31:45.719586Z","caller":"traceutil/trace.go:171","msg":"trace[530696294] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"191.560324ms","start":"2026-03-14T01:31:45.52797Z","end":"2026-03-14T01:31:45.71953Z","steps":["trace[530696294] 'process raft request' (duration: 127.225813ms)","trace[530696294] 'compare' (duration: 63.367641ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:46.445174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.069911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:46.445274Z","caller":"traceutil/trace.go:171","msg":"trace[1335979785] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:916; }","duration":"198.260475ms","start":"2026-03-14T01:31:46.246989Z","end":"2026-03-14T01:31:46.44525Z","steps":["trace[1335979785] 'count revisions from in-memory index tree' (duration: 197.928168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:46.44542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.222932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T01:31:46.4455Z","caller":"traceutil/trace.go:171","msg":"trace[272853672] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:916; }","duration":"570.366525ms","start":"2026-03-14T01:31:45.875115Z","end":"2026-03-14T01:31:46.445482Z","steps":["trace[272853672] 'count revisions from in-memory index tree' (duration: 570.066929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:46.44538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.103213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:31:46.445551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:45.875084Z","time spent":"570.457217ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-03-14T01:31:46.445621Z","caller":"traceutil/trace.go:171","msg":"trace[18345075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"513.369349ms","start":"2026-03-14T01:31:45.932219Z","end":"2026-03-14T01:31:46.445588Z","steps":["trace[18345075] 'range keys from in-memory index tree' (duration: 513.004101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:46.445719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:45.932207Z","time spent":"513.485142ms","remote":"127.0.0.1:54816","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-14T01:31:49.737215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.098686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:31:49.737474Z","caller":"traceutil/trace.go:171","msg":"trace[1257679707] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"192.93832ms","start":"2026-03-14T01:31:49.544517Z","end":"2026-03-14T01:31:49.737455Z","steps":["trace[1257679707] 'process raft request' (duration: 192.895449ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:49.737478Z","caller":"traceutil/trace.go:171","msg":"trace[198060625] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"193.110024ms","start":"2026-03-14T01:31:49.544339Z","end":"2026-03-14T01:31:49.737449Z","steps":["trace[198060625] 'process raft request' (duration: 88.678151ms)","trace[198060625] 'compare' (duration: 103.959553ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:49.737614Z","caller":"traceutil/trace.go:171","msg":"trace[189512510] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"193.177045ms","start":"2026-03-14T01:31:49.544404Z","end":"2026-03-14T01:31:49.737581Z","steps":["trace[189512510] 'process raft request' (duration: 192.951961ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:49.989132Z","caller":"traceutil/trace.go:171","msg":"trace[1418958481] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"194.125293ms","start":"2026-03-14T01:31:49.794962Z","end":"2026-03-14T01:31:49.989088Z","steps":["trace[1418958481] 'process raft request' (duration: 193.823537ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:50.670025Z","caller":"traceutil/trace.go:171","msg":"trace[103935550] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"351.753537ms","start":"2026-03-14T01:31:50.318241Z","end":"2026-03-14T01:31:50.669994Z","steps":["trace[103935550] 'process raft request' (duration: 351.570353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:50.670252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:50.318218Z","time spent":"351.908919ms","remote":"127.0.0.1:55086","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-14T01:31:51.919052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.97957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:31:51.919089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.490083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T01:31:51.919139Z","caller":"traceutil/trace.go:171","msg":"trace[422484556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"989.139744ms","start":"2026-03-14T01:31:50.929981Z","end":"2026-03-14T01:31:51.91912Z","steps":["trace[422484556] 'range keys from in-memory index tree' (duration: 988.849698ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:51.919196Z","caller":"traceutil/trace.go:171","msg":"trace[102412542] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:948; }","duration":"503.617796ms","start":"2026-03-14T01:31:51.415531Z","end":"2026-03-14T01:31:51.919148Z","steps":["trace[102412542] 'count revisions from in-memory index tree' (duration: 503.409002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:51.919213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:50.929947Z","time spent":"989.257487ms","remote":"127.0.0.1:54816","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-14T01:31:51.919249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:51.415515Z","time spent":"503.716918ms","remote":"127.0.0.1:55178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:31:51.919287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.944254ms","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-14T01:31:51.91931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.083317ms","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-14T01:31:51.919392Z","caller":"traceutil/trace.go:171","msg":"trace[1934310914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"177.170758ms","start":"2026-03-14T01:31:51.742199Z","end":"2026-03-14T01:31:51.919369Z","steps":["trace[1934310914] 'range keys from in-memory index tree' (duration: 176.852042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:51.919377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.279211ms","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-14T01:31:51.919495Z","caller":"traceutil/trace.go:171","msg":"trace[84857480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"177.427264ms","start":"2026-03-14T01:31:51.742041Z","end":"2026-03-14T01:31:51.919469Z","steps":["trace[84857480] 'range keys from in-memory index tree' (duration: 177.077227ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:51.919337Z","caller":"traceutil/trace.go:171","msg":"trace[1220607197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:948; }","duration":"376.052156ms","start":"2026-03-14T01:31:51.54327Z","end":"2026-03-14T01:31:51.919322Z","steps":["trace[1220607197] 'range keys from in-memory index tree' (duration: 375.70344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:51.919595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:51.543242Z","time spent":"376.341601ms","remote":"127.0.0.1:55086","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-14T01:31:51.919695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.136888ms","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-14T01:31:51.919738Z","caller":"traceutil/trace.go:171","msg":"trace[425676598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"178.212149ms","start":"2026-03-14T01:31:51.741509Z","end":"2026-03-14T01:31:51.919721Z","steps":["trace[425676598] 'range keys from in-memory index tree' (duration: 177.950594ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:52.16017Z","caller":"traceutil/trace.go:171","msg":"trace[1169109872] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:987; }","duration":"229.768857ms","start":"2026-03-14T01:31:51.930374Z","end":"2026-03-14T01:31:52.160142Z","steps":["trace[1169109872] 'read index received' (duration: 26.680327ms)","trace[1169109872] 'applied index is now lower than readState.Index' (duration: 203.08657ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:52.160346Z","caller":"traceutil/trace.go:171","msg":"trace[1112728870] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"236.106342ms","start":"2026-03-14T01:31:51.924208Z","end":"2026-03-14T01:31:52.160314Z","steps":["trace[1112728870] 'process raft request' (duration: 235.794366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:52.160413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.010422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:52.160471Z","caller":"traceutil/trace.go:171","msg":"trace[1723573267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"230.111664ms","start":"2026-03-14T01:31:51.930346Z","end":"2026-03-14T01:31:52.160458Z","steps":["trace[1723573267] 'agreement among raft nodes before linearized reading' (duration: 229.955301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:52.160498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.575168ms","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-14T01:31:52.160548Z","caller":"traceutil/trace.go:171","msg":"trace[2127345446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"167.67429ms","start":"2026-03-14T01:31:51.992861Z","end":"2026-03-14T01:31:52.160535Z","steps":["trace[2127345446] 'agreement among raft nodes before linearized reading' (duration: 167.454316ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:52.160628Z","caller":"traceutil/trace.go:171","msg":"trace[374145319] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"235.652283ms","start":"2026-03-14T01:31:51.924965Z","end":"2026-03-14T01:31:52.160618Z","steps":["trace[374145319] 'process raft request' (duration: 235.086242ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:52.160676Z","caller":"traceutil/trace.go:171","msg":"trace[244446408] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"236.622413ms","start":"2026-03-14T01:31:51.92404Z","end":"2026-03-14T01:31:52.160662Z","steps":["trace[244446408] 'process raft request' (duration: 137.174579ms)","trace[244446408] 'compare' (duration: 98.579357ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:54.140478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645045063473222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T01:31:54.609715Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156724694s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T01:31:54.705896Z","caller":"traceutil/trace.go:171","msg":"trace[1035964653] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.141194407s","start":"2026-03-14T01:31:53.564675Z","end":"2026-03-14T01:31:54.705869Z","steps":["trace[1035964653] 'process raft request' (duration: 1.140954982s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:54.706046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:53.564654Z","time spent":"1.14131772s","remote":"127.0.0.1:54892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T01:31:54.947227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.288616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:31:54.947316Z","caller":"traceutil/trace.go:171","msg":"trace[1457141226] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:992; }","duration":"1.3074336s","start":"2026-03-14T01:31:53.63987Z","end":"2026-03-14T01:31:54.947303Z","steps":["trace[1457141226] 'read index received' (duration: 970.062488ms)","trace[1457141226] 'applied index is now lower than readState.Index' (duration: 337.369882ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:54.947371Z","caller":"traceutil/trace.go:171","msg":"trace[1866222910] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"958.898456ms","start":"2026-03-14T01:31:53.988466Z","end":"2026-03-14T01:31:54.947364Z","steps":["trace[1866222910] 'process raft request' (duration: 717.391307ms)","trace[1866222910] 'compare' (duration: 241.024229ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:54.947402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:53.988413Z","time spent":"958.972848ms","remote":"127.0.0.1:55086","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-14T01:31:54.947539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307677545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:54.947559Z","caller":"traceutil/trace.go:171","msg":"trace[1968078549] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:956; }","duration":"1.307745166s","start":"2026-03-14T01:31:53.639807Z","end":"2026-03-14T01:31:54.947552Z","steps":["trace[1968078549] 'agreement among raft nodes before linearized reading' (duration: 1.307673495s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:54.947578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:53.63978Z","time spent":"1.307793457s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-14T01:31:54.998353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.544466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-14T01:31:54.998352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.929069ms","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-14T01:31:54.998384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.237315ms","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-14T01:31:54.998418Z","caller":"traceutil/trace.go:171","msg":"trace[130235720] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"775.668878ms","start":"2026-03-14T01:31:54.22274Z","end":"2026-03-14T01:31:54.998409Z","steps":["trace[130235720] 'agreement among raft nodes before linearized reading' (duration: 775.429764ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:54.998445Z","caller":"traceutil/trace.go:171","msg":"trace[2079056400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"833.047321ms","start":"2026-03-14T01:31:54.165379Z","end":"2026-03-14T01:31:54.998426Z","steps":["trace[2079056400] 'agreement among raft nodes before linearized reading' (duration: 832.770796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:54.99843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.369277ms","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-14T01:31:54.998122Z","caller":"traceutil/trace.go:171","msg":"trace[820265513] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"286.900876ms","start":"2026-03-14T01:31:54.711198Z","end":"2026-03-14T01:31:54.998098Z","steps":["trace[820265513] 'process raft request' (duration: 286.750433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:54.998499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:54.16534Z","time spent":"833.149164ms","remote":"127.0.0.1:55086","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-14T01:31:54.998461Z","caller":"traceutil/trace.go:171","msg":"trace[1955125198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"832.368968ms","start":"2026-03-14T01:31:54.166069Z","end":"2026-03-14T01:31:54.998438Z","steps":["trace[1955125198] 'agreement among raft nodes before linearized reading' (duration: 832.140963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:54.998467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:54.222725Z","time spent":"775.736159ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T01:31:54.998518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037366187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-14T01:31:54.998585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:54.166038Z","time spent":"832.53482ms","remote":"127.0.0.1:55086","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-14T01:31:54.998597Z","caller":"traceutil/trace.go:171","msg":"trace[39218818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"1.037495689s","start":"2026-03-14T01:31:53.961083Z","end":"2026-03-14T01:31:54.998579Z","steps":["trace[39218818] 'agreement among raft nodes before linearized reading' (duration: 1.037250845s)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:54.998527Z","caller":"traceutil/trace.go:171","msg":"trace[1253019458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"833.5262ms","start":"2026-03-14T01:31:54.16498Z","end":"2026-03-14T01:31:54.998506Z","steps":["trace[1253019458] 'agreement among raft nodes before linearized reading' (duration: 833.264645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:54.998642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:53.961068Z","time spent":"1.03756489s","remote":"127.0.0.1:55086","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-14T01:31:54.998327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056892993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:31:54.998692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:54.164954Z","time spent":"833.722724ms","remote":"127.0.0.1:55086","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-14T01:31:54.998709Z","caller":"traceutil/trace.go:171","msg":"trace[944134505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"1.057353021s","start":"2026-03-14T01:31:53.941344Z","end":"2026-03-14T01:31:54.998697Z","steps":["trace[944134505] 'agreement among raft nodes before linearized reading' (duration: 1.056767869s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:54.998751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:53.941303Z","time spent":"1.057436972s","remote":"127.0.0.1:54816","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-14T01:31:55.166697Z","caller":"traceutil/trace.go:171","msg":"trace[2042627823] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"162.938237ms","start":"2026-03-14T01:31:55.003732Z","end":"2026-03-14T01:31:55.166671Z","steps":["trace[2042627823] 'process raft request' (duration: 162.811054ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:55.166769Z","caller":"traceutil/trace.go:171","msg":"trace[829515401] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"161.909657ms","start":"2026-03-14T01:31:55.004817Z","end":"2026-03-14T01:31:55.166727Z","steps":["trace[829515401] 'process raft request' (duration: 161.774734ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:55.166791Z","caller":"traceutil/trace.go:171","msg":"trace[1615640810] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:997; }","duration":"122.994457ms","start":"2026-03-14T01:31:55.043764Z","end":"2026-03-14T01:31:55.166758Z","steps":["trace[1615640810] 'read index received' (duration: 122.728352ms)","trace[1615640810] 'applied index is now lower than readState.Index' (duration: 264.885µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:55.166904Z","caller":"traceutil/trace.go:171","msg":"trace[4917760] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"161.233135ms","start":"2026-03-14T01:31:55.005661Z","end":"2026-03-14T01:31:55.166894Z","steps":["trace[4917760] 'process raft request' (duration: 161.0096ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:55.167061Z","caller":"traceutil/trace.go:171","msg":"trace[1540356443] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"160.694444ms","start":"2026-03-14T01:31:55.006355Z","end":"2026-03-14T01:31:55.167049Z","steps":["trace[1540356443] 'process raft request' (duration: 160.353187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:55.167232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.432757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-14T01:31:55.167316Z","caller":"traceutil/trace.go:171","msg":"trace[1424410908] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:964; }","duration":"123.58831ms","start":"2026-03-14T01:31:55.043709Z","end":"2026-03-14T01:31:55.167297Z","steps":["trace[1424410908] 'agreement among raft nodes before linearized reading' (duration: 123.341425ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:55.166764Z","caller":"traceutil/trace.go:171","msg":"trace[1773760101] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"161.155692ms","start":"2026-03-14T01:31:55.005583Z","end":"2026-03-14T01:31:55.166739Z","steps":["trace[1773760101] 'process raft request' (duration: 161.05276ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:55.167624Z","caller":"traceutil/trace.go:171","msg":"trace[57761159] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"163.732084ms","start":"2026-03-14T01:31:55.002928Z","end":"2026-03-14T01:31:55.16666Z","steps":["trace[57761159] 'process raft request' (duration: 163.417718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:55.227323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.565687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:31:55.227326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.312102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:55.22739Z","caller":"traceutil/trace.go:171","msg":"trace[267532951] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:965; }","duration":"178.66833ms","start":"2026-03-14T01:31:55.048705Z","end":"2026-03-14T01:31:55.227374Z","steps":["trace[267532951] 'agreement among raft nodes before linearized reading' (duration: 178.501036ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:55.227409Z","caller":"traceutil/trace.go:171","msg":"trace[2124689314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"178.415784ms","start":"2026-03-14T01:31:55.048973Z","end":"2026-03-14T01:31:55.227389Z","steps":["trace[2124689314] 'agreement among raft nodes before linearized reading' (duration: 178.247731ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:55.227787Z","caller":"traceutil/trace.go:171","msg":"trace[1361984333] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"181.75681ms","start":"2026-03-14T01:31:55.046016Z","end":"2026-03-14T01:31:55.227773Z","steps":["trace[1361984333] 'process raft request' (duration: 181.036006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:55.487835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.901051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:31:55.488156Z","caller":"traceutil/trace.go:171","msg":"trace[1344927340] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"315.793797ms","start":"2026-03-14T01:31:55.172342Z","end":"2026-03-14T01:31:55.488136Z","steps":["trace[1344927340] 'process raft request' (duration: 125.485768ms)","trace[1344927340] 'compare' (duration: 189.710958ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:55.488234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:55.172318Z","time spent":"315.882419ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:32:04.170133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.44024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T01:32:04.170263Z","caller":"traceutil/trace.go:171","msg":"trace[1199209924] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1026; }","duration":"239.641664ms","start":"2026-03-14T01:32:03.930596Z","end":"2026-03-14T01:32:04.170238Z","steps":["trace[1199209924] 'count revisions from in-memory index tree' (duration: 239.286907ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:05.659111Z","caller":"traceutil/trace.go:171","msg":"trace[1141197667] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"440.296252ms","start":"2026-03-14T01:32:05.218788Z","end":"2026-03-14T01:32:05.659084Z","steps":["trace[1141197667] 'read index received' (duration: 440.165069ms)","trace[1141197667] 'applied index is now lower than readState.Index' (duration: 130.013µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:05.65917Z","caller":"traceutil/trace.go:171","msg":"trace[79390492] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"598.629021ms","start":"2026-03-14T01:32:05.060508Z","end":"2026-03-14T01:32:05.659137Z","steps":["trace[79390492] 'process raft request' (duration: 598.413737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:05.659324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:05.060488Z","time spent":"598.753583ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:32:05.659575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.760541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-14T01:32:05.659758Z","caller":"traceutil/trace.go:171","msg":"trace[1034409138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1028; }","duration":"441.023806ms","start":"2026-03-14T01:32:05.21871Z","end":"2026-03-14T01:32:05.659734Z","steps":["trace[1034409138] 'agreement among raft nodes before linearized reading' (duration: 440.464984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:05.659871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:05.218679Z","time spent":"441.182179ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-14T01:32:06.859381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.594247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:32:06.859663Z","caller":"traceutil/trace.go:171","msg":"trace[512365834] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"1.195973976s","start":"2026-03-14T01:32:05.663668Z","end":"2026-03-14T01:32:06.859642Z","steps":["trace[512365834] 'process raft request' (duration: 1.195860634s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:06.859729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:05.663642Z","time spent":"1.196059847s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:32:06.859893Z","caller":"traceutil/trace.go:171","msg":"trace[1743241601] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"1.200653286s","start":"2026-03-14T01:32:05.659191Z","end":"2026-03-14T01:32:06.859844Z","steps":["trace[1743241601] 'read index received' (duration: 346.376046ms)","trace[1743241601] 'applied index is now lower than readState.Index' (duration: 854.27091ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:06.860051Z","caller":"traceutil/trace.go:171","msg":"trace[900655118] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.533954452s","start":"2026-03-14T01:32:05.326079Z","end":"2026-03-14T01:32:06.860034Z","steps":["trace[900655118] 'process raft request' (duration: 679.555729ms)","trace[900655118] 'compare' (duration: 852.942585ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:32:06.860306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640597871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-14T01:32:06.860299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640852797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-14T01:32:06.860301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:05.326052Z","time spent":"1.534028083s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:32:06.860395Z","caller":"traceutil/trace.go:171","msg":"trace[948215012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1030; }","duration":"1.641005279s","start":"2026-03-14T01:32:05.219364Z","end":"2026-03-14T01:32:06.86037Z","steps":["trace[948215012] 'agreement among raft nodes before linearized reading' (duration: 1.640698463s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:06.860441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:05.219343Z","time spent":"1.641089391s","remote":"127.0.0.1:55086","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-14T01:32:06.860574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3566883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-14T01:32:06.860603Z","caller":"traceutil/trace.go:171","msg":"trace[1545094965] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1030; }","duration":"1.356754092s","start":"2026-03-14T01:32:05.503842Z","end":"2026-03-14T01:32:06.860596Z","steps":["trace[1545094965] 'agreement among raft nodes before linearized reading' (duration: 1.356675701s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:06.860626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:05.503827Z","time spent":"1.356794822s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-14T01:32:06.860349Z","caller":"traceutil/trace.go:171","msg":"trace[1525040933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"1.640656722s","start":"2026-03-14T01:32:05.21968Z","end":"2026-03-14T01:32:06.860337Z","steps":["trace[1525040933] 'agreement among raft nodes before linearized reading' (duration: 1.64051478s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:06.860667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:05.219666Z","time spent":"1.640989929s","remote":"127.0.0.1:55086","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-14T01:32:06.86066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641771003s","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-14T01:32:06.860758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.738972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-14T01:32:06.86076Z","caller":"traceutil/trace.go:171","msg":"trace[1938362835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"1.641919837s","start":"2026-03-14T01:32:05.218828Z","end":"2026-03-14T01:32:06.860748Z","steps":["trace[1938362835] 'agreement among raft nodes before linearized reading' (duration: 1.641508198s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:06.860752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64103468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-14T01:32:06.860794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:05.218802Z","time spent":"1.641984858s","remote":"127.0.0.1:55086","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-14T01:32:06.860828Z","caller":"traceutil/trace.go:171","msg":"trace[2092890662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"1.641143623s","start":"2026-03-14T01:32:05.219666Z","end":"2026-03-14T01:32:06.86081Z","steps":["trace[2092890662] 'agreement among raft nodes before linearized reading' (duration: 1.640888077s)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:06.860795Z","caller":"traceutil/trace.go:171","msg":"trace[1915296279] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1030; }","duration":"611.769162ms","start":"2026-03-14T01:32:06.249013Z","end":"2026-03-14T01:32:06.860782Z","steps":["trace[1915296279] 'agreement among raft nodes before linearized reading' (duration: 611.68534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:06.860901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:06.248988Z","time spent":"611.904244ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-14T01:32:06.860875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:05.219655Z","time spent":"1.641210334s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-14T01:33:51.582922Z","caller":"traceutil/trace.go:171","msg":"trace[1574499290] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1518; }","duration":"145.269771ms","start":"2026-03-14T01:33:51.437634Z","end":"2026-03-14T01:33:51.582904Z","steps":["trace[1574499290] 'read index received' (duration: 145.106388ms)","trace[1574499290] 'applied index is now lower than readState.Index' (duration: 160.843µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:33:51.583003Z","caller":"traceutil/trace.go:171","msg":"trace[377662890] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"157.228891ms","start":"2026-03-14T01:33:51.425727Z","end":"2026-03-14T01:33:51.582956Z","steps":["trace[377662890] 'process raft request' (duration: 157.063048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:51.583123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.478484ms","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-14T01:33:51.583149Z","caller":"traceutil/trace.go:171","msg":"trace[126206846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1457; }","duration":"145.577206ms","start":"2026-03-14T01:33:51.437563Z","end":"2026-03-14T01:33:51.58314Z","steps":["trace[126206846] 'agreement among raft nodes before linearized reading' (duration: 145.438493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:51.583161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.543861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-03-14T01:33:51.583163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.857324ms","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-14T01:33:51.583212Z","caller":"traceutil/trace.go:171","msg":"trace[931917067] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1457; }","duration":"111.635972ms","start":"2026-03-14T01:33:51.471564Z","end":"2026-03-14T01:33:51.5832Z","steps":["trace[931917067] 'agreement among raft nodes before linearized reading' (duration: 111.50483ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:51.58322Z","caller":"traceutil/trace.go:171","msg":"trace[1939472617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1457; }","duration":"114.931035ms","start":"2026-03-14T01:33:51.468275Z","end":"2026-03-14T01:33:51.583206Z","steps":["trace[1939472617] 'agreement among raft nodes before linearized reading' (duration: 114.785413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:51.583208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.057708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-14T01:33:51.583235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.563148ms","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-14T01:33:51.5833Z","caller":"traceutil/trace.go:171","msg":"trace[77282213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1457; }","duration":"115.237632ms","start":"2026-03-14T01:33:51.46804Z","end":"2026-03-14T01:33:51.583278Z","steps":["trace[77282213] 'agreement among raft nodes before linearized reading' (duration: 115.005657ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:51.583314Z","caller":"traceutil/trace.go:171","msg":"trace[1639737123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1457; }","duration":"114.648611ms","start":"2026-03-14T01:33:51.46864Z","end":"2026-03-14T01:33:51.583289Z","steps":["trace[1639737123] 'agreement among raft nodes before linearized reading' (duration: 114.423897ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:51.69262Z","caller":"traceutil/trace.go:171","msg":"trace[1734312286] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"106.573514ms","start":"2026-03-14T01:33:51.586005Z","end":"2026-03-14T01:33:51.692578Z","steps":["trace[1734312286] 'process raft request' (duration: 85.984848ms)","trace[1734312286] 'compare' (duration: 20.456224ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:33:51.693209Z","caller":"traceutil/trace.go:171","msg":"trace[793440914] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"106.474863ms","start":"2026-03-14T01:33:51.586719Z","end":"2026-03-14T01:33:51.693193Z","steps":["trace[793440914] 'process raft request' (duration: 106.383111ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:51.693367Z","caller":"traceutil/trace.go:171","msg":"trace[182741169] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"106.637566ms","start":"2026-03-14T01:33:51.586721Z","end":"2026-03-14T01:33:51.693359Z","steps":["trace[182741169] 'process raft request' (duration: 106.445742ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:51.69349Z","caller":"traceutil/trace.go:171","msg":"trace[890181636] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"106.744797ms","start":"2026-03-14T01:33:51.586738Z","end":"2026-03-14T01:33:51.693483Z","steps":["trace[890181636] 'process raft request' (duration: 106.595424ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:51.693566Z","caller":"traceutil/trace.go:171","msg":"trace[273097665] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"104.984854ms","start":"2026-03-14T01:33:51.588573Z","end":"2026-03-14T01:33:51.693558Z","steps":["trace[273097665] 'process raft request' (duration: 104.884212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:33:52.204495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.594776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-03-14T01:33:52.204556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.830301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:33:52.204598Z","caller":"traceutil/trace.go:171","msg":"trace[829952178] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1462; }","duration":"132.76093ms","start":"2026-03-14T01:33:52.071806Z","end":"2026-03-14T01:33:52.204567Z","steps":["trace[829952178] 'range keys from in-memory index tree' (duration: 132.344941ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:52.204643Z","caller":"traceutil/trace.go:171","msg":"trace[1180095864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1462; }","duration":"271.964493ms","start":"2026-03-14T01:33:51.932661Z","end":"2026-03-14T01:33:52.204625Z","steps":["trace[1180095864] 'range keys from in-memory index tree' (duration: 271.490284ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:52.358473Z","caller":"traceutil/trace.go:171","msg":"trace[935199552] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"149.638505ms","start":"2026-03-14T01:33:52.208816Z","end":"2026-03-14T01:33:52.358454Z","steps":["trace[935199552] 'process raft request' (duration: 149.523813ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:03.955253Z","caller":"traceutil/trace.go:171","msg":"trace[1375549907] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"112.076001ms","start":"2026-03-14T01:34:03.843143Z","end":"2026-03-14T01:34:03.955219Z","steps":["trace[1375549907] 'process raft request' (duration: 104.352292ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:03.955356Z","caller":"traceutil/trace.go:171","msg":"trace[963556224] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"112.084641ms","start":"2026-03-14T01:34:03.843237Z","end":"2026-03-14T01:34:03.955321Z","steps":["trace[963556224] 'process raft request' (duration: 111.881607ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:03.955829Z","caller":"traceutil/trace.go:171","msg":"trace[893317120] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"111.748984ms","start":"2026-03-14T01:34:03.844061Z","end":"2026-03-14T01:34:03.95581Z","steps":["trace[893317120] 'process raft request' (duration: 111.690973ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:03.955939Z","caller":"traceutil/trace.go:171","msg":"trace[1624039410] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"111.956559ms","start":"2026-03-14T01:34:03.843974Z","end":"2026-03-14T01:34:03.95593Z","steps":["trace[1624039410] 'process raft request' (duration: 111.662633ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:06.147997Z","caller":"traceutil/trace.go:171","msg":"trace[1606644925] linearizableReadLoop","detail":"{readStateIndex:1580; appliedIndex:1579; }","duration":"216.075826ms","start":"2026-03-14T01:34:05.931899Z","end":"2026-03-14T01:34:06.147975Z","steps":["trace[1606644925] 'read index received' (duration: 216.015635ms)","trace[1606644925] 'applied index is now lower than readState.Index' (duration: 59.001µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:34:06.148208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.28984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:34:06.148289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.763309ms","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-14T01:34:06.148296Z","caller":"traceutil/trace.go:171","msg":"trace[1912246850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1515; }","duration":"216.430743ms","start":"2026-03-14T01:34:05.931843Z","end":"2026-03-14T01:34:06.148274Z","steps":["trace[1912246850] 'agreement among raft nodes before linearized reading' (duration: 216.2793ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:06.148313Z","caller":"traceutil/trace.go:171","msg":"trace[715724946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1515; }","duration":"187.8232ms","start":"2026-03-14T01:34:05.960482Z","end":"2026-03-14T01:34:06.148305Z","steps":["trace[715724946] 'agreement among raft nodes before linearized reading' (duration: 187.77873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:06.148243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.068626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-14T01:34:06.148467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.748757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-14T01:34:06.14847Z","caller":"traceutil/trace.go:171","msg":"trace[711095154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1515; }","duration":"188.29807ms","start":"2026-03-14T01:34:05.96012Z","end":"2026-03-14T01:34:06.148418Z","steps":["trace[711095154] 'agreement among raft nodes before linearized reading' (duration: 188.020035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:06.148354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.07458ms","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-14T01:34:06.148526Z","caller":"traceutil/trace.go:171","msg":"trace[621021148] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1515; }","duration":"134.822979ms","start":"2026-03-14T01:34:06.013677Z","end":"2026-03-14T01:34:06.148504Z","steps":["trace[621021148] 'agreement among raft nodes before linearized reading' (duration: 134.720507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:06.148499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.045739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-14T01:34:06.148579Z","caller":"traceutil/trace.go:171","msg":"trace[998470853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1515; }","duration":"183.334434ms","start":"2026-03-14T01:34:05.965227Z","end":"2026-03-14T01:34:06.148562Z","steps":["trace[998470853] 'agreement among raft nodes before linearized reading' (duration: 182.978418ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:06.148635Z","caller":"traceutil/trace.go:171","msg":"trace[2007308008] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1515; }","duration":"184.225412ms","start":"2026-03-14T01:34:05.964378Z","end":"2026-03-14T01:34:06.148604Z","steps":["trace[2007308008] 'agreement among raft nodes before linearized reading' (duration: 183.819484ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:06.149075Z","caller":"traceutil/trace.go:171","msg":"trace[435031933] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"221.669254ms","start":"2026-03-14T01:34:05.926453Z","end":"2026-03-14T01:34:06.148122Z","steps":["trace[435031933] 'process raft request' (duration: 221.417428ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:06.27134Z","caller":"traceutil/trace.go:171","msg":"trace[113661729] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"117.361113ms","start":"2026-03-14T01:34:06.153953Z","end":"2026-03-14T01:34:06.271314Z","steps":["trace[113661729] 'process raft request' (duration: 71.687612ms)","trace[113661729] 'compare' (duration: 45.546688ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:06.271752Z","caller":"traceutil/trace.go:171","msg":"trace[1005354404] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"117.336282ms","start":"2026-03-14T01:34:06.154405Z","end":"2026-03-14T01:34:06.271741Z","steps":["trace[1005354404] 'process raft request' (duration: 117.264ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:06.271786Z","caller":"traceutil/trace.go:171","msg":"trace[1723537046] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"117.664308ms","start":"2026-03-14T01:34:06.154084Z","end":"2026-03-14T01:34:06.271748Z","steps":["trace[1723537046] 'process raft request' (duration: 117.515455ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:06.27203Z","caller":"traceutil/trace.go:171","msg":"trace[1694022737] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"117.571687ms","start":"2026-03-14T01:34:06.154447Z","end":"2026-03-14T01:34:06.272018Z","steps":["trace[1694022737] 'process raft request' (duration: 117.259961ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:06.272261Z","caller":"traceutil/trace.go:171","msg":"trace[1645558586] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"117.490545ms","start":"2026-03-14T01:34:06.154757Z","end":"2026-03-14T01:34:06.272247Z","steps":["trace[1645558586] 'process raft request' (duration: 117.21342ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:20.475553Z","caller":"traceutil/trace.go:171","msg":"trace[1573219099] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"136.755264ms","start":"2026-03-14T01:34:20.338769Z","end":"2026-03-14T01:34:20.475524Z","steps":["trace[1573219099] 'process raft request' (duration: 87.13117ms)","trace[1573219099] 'compare' (duration: 49.384318ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:34:20.475755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.787583ms","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-14T01:34:20.475827Z","caller":"traceutil/trace.go:171","msg":"trace[1049929440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1577; }","duration":"138.866594ms","start":"2026-03-14T01:34:20.336944Z","end":"2026-03-14T01:34:20.47581Z","steps":["trace[1049929440] 'agreement among raft nodes before linearized reading' (duration: 138.684261ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:26.643405Z","caller":"traceutil/trace.go:171","msg":"trace[2053571928] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"106.314213ms","start":"2026-03-14T01:34:26.537071Z","end":"2026-03-14T01:34:26.643385Z","steps":["trace[2053571928] 'process raft request' (duration: 106.195331ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:26.658113Z","caller":"traceutil/trace.go:171","msg":"trace[1497408437] linearizableReadLoop","detail":"{readStateIndex:1670; appliedIndex:1668; }","duration":"117.034531ms","start":"2026-03-14T01:34:26.541062Z","end":"2026-03-14T01:34:26.658097Z","steps":["trace[1497408437] 'read index received' (duration: 102.210334ms)","trace[1497408437] 'applied index is now lower than readState.Index' (duration: 14.823667ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:34:26.658264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.186744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-14T01:34:26.658204Z","caller":"traceutil/trace.go:171","msg":"trace[1360814347] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"119.212203ms","start":"2026-03-14T01:34:26.538968Z","end":"2026-03-14T01:34:26.65818Z","steps":["trace[1360814347] 'process raft request' (duration: 119.0259ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:26.658293Z","caller":"traceutil/trace.go:171","msg":"trace[629006178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1601; }","duration":"117.227654ms","start":"2026-03-14T01:34:26.541058Z","end":"2026-03-14T01:34:26.658285Z","steps":["trace[629006178] 'agreement among raft nodes before linearized reading' (duration: 117.104812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:29.062407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.93406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:34:29.062495Z","caller":"traceutil/trace.go:171","msg":"trace[53488514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1610; }","duration":"131.087853ms","start":"2026-03-14T01:34:28.931389Z","end":"2026-03-14T01:34:29.062476Z","steps":["trace[53488514] 'range keys from in-memory index tree' (duration: 130.835089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:29.062556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.780546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-14T01:34:29.062608Z","caller":"traceutil/trace.go:171","msg":"trace[384712179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1610; }","duration":"320.872818ms","start":"2026-03-14T01:34:28.741721Z","end":"2026-03-14T01:34:29.062594Z","steps":["trace[384712179] 'range keys from in-memory index tree' (duration: 320.607163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:29.062653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:28.741701Z","time spent":"320.943309ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T01:34:29.062756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.88637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363678"} {"level":"info","ts":"2026-03-14T01:34:29.062859Z","caller":"traceutil/trace.go:171","msg":"trace[1403428713] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1610; }","duration":"316.006783ms","start":"2026-03-14T01:34:28.746824Z","end":"2026-03-14T01:34:29.062831Z","steps":["trace[1403428713] 'range keys from in-memory index tree' (duration: 315.524843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:29.062932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:28.746788Z","time spent":"316.126555ms","remote":"127.0.0.1:54898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":6,"response size":363701,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-03-14T01:34:34.705493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.526349ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:34:34.705652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.577205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-14T01:34:34.705701Z","caller":"traceutil/trace.go:171","msg":"trace[381865432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1631; }","duration":"347.703367ms","start":"2026-03-14T01:34:34.357979Z","end":"2026-03-14T01:34:34.705683Z","steps":["trace[381865432] 'range keys from in-memory index tree' (duration: 347.477403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:34.705743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:34:34.357963Z","time spent":"347.772498ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-14T01:34:34.705649Z","caller":"traceutil/trace.go:171","msg":"trace[1223069981] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1631; }","duration":"104.690992ms","start":"2026-03-14T01:34:34.600934Z","end":"2026-03-14T01:34:34.705625Z","steps":["trace[1223069981] 'range keys from in-memory index tree' (duration: 104.511599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:34.705543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.993805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-14T01:34:34.705967Z","caller":"traceutil/trace.go:171","msg":"trace[1313623174] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1631; }","duration":"127.447543ms","start":"2026-03-14T01:34:34.578489Z","end":"2026-03-14T01:34:34.705937Z","steps":["trace[1313623174] 'range keys from in-memory index tree' (duration: 126.839482ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:36.904423Z","caller":"traceutil/trace.go:171","msg":"trace[1464896915] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"146.698606ms","start":"2026-03-14T01:34:36.757696Z","end":"2026-03-14T01:34:36.904395Z","steps":["trace[1464896915] 'process raft request' (duration: 118.619521ms)","trace[1464896915] 'compare' (duration: 27.909251ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:36.904628Z","caller":"traceutil/trace.go:171","msg":"trace[1708327378] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"146.530762ms","start":"2026-03-14T01:34:36.75807Z","end":"2026-03-14T01:34:36.904601Z","steps":["trace[1708327378] 'process raft request' (duration: 146.288408ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:44.284451Z","caller":"traceutil/trace.go:171","msg":"trace[79267229] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"115.505511ms","start":"2026-03-14T01:34:44.168922Z","end":"2026-03-14T01:34:44.284428Z","steps":["trace[79267229] 'process raft request' (duration: 115.335467ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:47.522815Z","caller":"traceutil/trace.go:171","msg":"trace[1097139889] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"248.839059ms","start":"2026-03-14T01:34:47.273948Z","end":"2026-03-14T01:34:47.522787Z","steps":["trace[1097139889] 'process raft request' (duration: 248.676636ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:48.184257Z","caller":"traceutil/trace.go:171","msg":"trace[350288186] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"161.082496ms","start":"2026-03-14T01:34:48.023147Z","end":"2026-03-14T01:34:48.18423Z","steps":["trace[350288186] 'process raft request' (duration: 105.481796ms)","trace[350288186] 'compare' (duration: 55.481977ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:48.622149Z","caller":"traceutil/trace.go:171","msg":"trace[1983714821] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"168.407678ms","start":"2026-03-14T01:34:48.453718Z","end":"2026-03-14T01:34:48.622125Z","steps":["trace[1983714821] 'process raft request' (duration: 168.252186ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:09.351202Z","caller":"traceutil/trace.go:171","msg":"trace[932751574] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"113.274838ms","start":"2026-03-14T01:35:09.237902Z","end":"2026-03-14T01:35:09.351176Z","steps":["trace[932751574] 'process raft request' (duration: 113.131936ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:09.541934Z","caller":"traceutil/trace.go:171","msg":"trace[1555826927] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1877; }","duration":"147.882921ms","start":"2026-03-14T01:35:09.394024Z","end":"2026-03-14T01:35:09.541907Z","steps":["trace[1555826927] 'read index received' (duration: 113.807419ms)","trace[1555826927] 'applied index is now lower than readState.Index' (duration: 34.074352ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:35:09.5421Z","caller":"traceutil/trace.go:171","msg":"trace[1230084880] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"163.32058ms","start":"2026-03-14T01:35:09.378739Z","end":"2026-03-14T01:35:09.542059Z","steps":["trace[1230084880] 'process raft request' (duration: 129.126715ms)","trace[1230084880] 'compare' (duration: 33.92413ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:35:09.542426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.37449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-14T01:35:09.542529Z","caller":"traceutil/trace.go:171","msg":"trace[1727774177] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1799; }","duration":"148.497832ms","start":"2026-03-14T01:35:09.394008Z","end":"2026-03-14T01:35:09.542505Z","steps":["trace[1727774177] 'agreement among raft nodes before linearized reading' (duration: 148.160286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:09.542715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.043907ms","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-14T01:35:09.542766Z","caller":"traceutil/trace.go:171","msg":"trace[1488408470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1799; }","duration":"141.135299ms","start":"2026-03-14T01:35:09.40162Z","end":"2026-03-14T01:35:09.542755Z","steps":["trace[1488408470] 'agreement among raft nodes before linearized reading' (duration: 140.607929ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:10.614458Z","caller":"traceutil/trace.go:171","msg":"trace[1529341819] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"143.523916ms","start":"2026-03-14T01:35:10.470916Z","end":"2026-03-14T01:35:10.61444Z","steps":["trace[1529341819] 'process raft request' (duration: 143.403214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:19.892382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.886612ms","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-14T01:35:19.892498Z","caller":"traceutil/trace.go:171","msg":"trace[1743184296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1841; }","duration":"698.016785ms","start":"2026-03-14T01:35:19.194456Z","end":"2026-03-14T01:35:19.892472Z","steps":["trace[1743184296] 'range keys from in-memory index tree' (duration: 697.71478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:19.892573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:19.194408Z","time spent":"698.149347ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-14T01:35:19.892724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536091129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T01:35:19.89284Z","caller":"traceutil/trace.go:171","msg":"trace[1455051358] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:1841; }","duration":"1.536245202s","start":"2026-03-14T01:35:18.356576Z","end":"2026-03-14T01:35:19.892821Z","steps":["trace[1455051358] 'range keys from in-memory index tree' (duration: 1.535826424s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:19.892933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:18.356554Z","time spent":"1.536362194s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"warn","ts":"2026-03-14T01:35:19.893225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.240439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-14T01:35:19.893286Z","caller":"traceutil/trace.go:171","msg":"trace[1274015017] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1841; }","duration":"415.30262ms","start":"2026-03-14T01:35:19.477969Z","end":"2026-03-14T01:35:19.893272Z","steps":["trace[1274015017] 'range keys from in-memory index tree' (duration: 415.119136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:19.893328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:19.477928Z","time spent":"415.387681ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-14T01:35:19.893454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244032782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-14T01:35:19.893547Z","caller":"traceutil/trace.go:171","msg":"trace[1247825883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1841; }","duration":"1.244168365s","start":"2026-03-14T01:35:18.649364Z","end":"2026-03-14T01:35:19.893532Z","steps":["trace[1247825883] 'range keys from in-memory index tree' (duration: 1.243873169s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:19.893638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:18.649342Z","time spent":"1.244279476s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T01:35:19.893786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.798026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-14T01:35:19.893847Z","caller":"traceutil/trace.go:171","msg":"trace[960374546] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1841; }","duration":"284.903298ms","start":"2026-03-14T01:35:19.608927Z","end":"2026-03-14T01:35:19.893831Z","steps":["trace[960374546] 'range keys from in-memory index tree' (duration: 284.658244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:19.892523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.55715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:35:19.89409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.799052ms","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-14T01:35:19.894134Z","caller":"traceutil/trace.go:171","msg":"trace[1105831439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1841; }","duration":"141.880724ms","start":"2026-03-14T01:35:19.75224Z","end":"2026-03-14T01:35:19.89412Z","steps":["trace[1105831439] 'range keys from in-memory index tree' (duration: 141.678879ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:19.894106Z","caller":"traceutil/trace.go:171","msg":"trace[1602894557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1841; }","duration":"961.173572ms","start":"2026-03-14T01:35:18.932916Z","end":"2026-03-14T01:35:19.894089Z","steps":["trace[1602894557] 'range keys from in-memory index tree' (duration: 959.483799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:19.894259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:18.932895Z","time spent":"961.345695ms","remote":"127.0.0.1:54816","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-14T01:35:19.894004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.972686ms","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-14T01:35:19.894587Z","caller":"traceutil/trace.go:171","msg":"trace[2044060403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1841; }","duration":"158.590218ms","start":"2026-03-14T01:35:19.735979Z","end":"2026-03-14T01:35:19.894569Z","steps":["trace[2044060403] 'range keys from in-memory index tree' (duration: 157.796462ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:20.098499Z","caller":"traceutil/trace.go:171","msg":"trace[604768012] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1922; }","duration":"201.99298ms","start":"2026-03-14T01:35:19.896448Z","end":"2026-03-14T01:35:20.098441Z","steps":["trace[604768012] 'read index received' (duration: 201.969179ms)","trace[604768012] 'applied index is now lower than readState.Index' (duration: 19.361µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:35:20.098666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.185933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:35:20.098796Z","caller":"traceutil/trace.go:171","msg":"trace[1616175359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1841; }","duration":"202.342407ms","start":"2026-03-14T01:35:19.896426Z","end":"2026-03-14T01:35:20.098768Z","steps":["trace[1616175359] 'agreement among raft nodes before linearized reading' (duration: 202.154092ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:20.304558Z","caller":"traceutil/trace.go:171","msg":"trace[229653286] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"404.892727ms","start":"2026-03-14T01:35:19.899639Z","end":"2026-03-14T01:35:20.304532Z","steps":["trace[229653286] 'process raft request' (duration: 404.793135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:20.30473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:19.899628Z","time spent":"405.00731ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:35:20.304764Z","caller":"traceutil/trace.go:171","msg":"trace[1574952732] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"405.053101ms","start":"2026-03-14T01:35:19.899687Z","end":"2026-03-14T01:35:20.304741Z","steps":["trace[1574952732] 'process raft request' (duration: 404.801075ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:20.304733Z","caller":"traceutil/trace.go:171","msg":"trace[1888424202] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"405.258865ms","start":"2026-03-14T01:35:19.899403Z","end":"2026-03-14T01:35:20.304662Z","steps":["trace[1888424202] 'process raft request' (duration: 362.587667ms)","trace[1888424202] 'compare' (duration: 42.306631ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:35:20.304865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:19.899675Z","time spent":"405.142673ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:35:20.304975Z","caller":"traceutil/trace.go:171","msg":"trace[841321017] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"405.111542ms","start":"2026-03-14T01:35:19.899842Z","end":"2026-03-14T01:35:20.304954Z","steps":["trace[841321017] 'process raft request' (duration: 404.831667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:20.305107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:19.899832Z","time spent":"405.217265ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:35:20.305125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:19.899388Z","time spent":"405.469039ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:35:20.305216Z","caller":"traceutil/trace.go:171","msg":"trace[2024946173] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"405.334097ms","start":"2026-03-14T01:35:19.899864Z","end":"2026-03-14T01:35:20.305198Z","steps":["trace[2024946173] 'process raft request' (duration: 405.031001ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:20.305329Z","caller":"traceutil/trace.go:171","msg":"trace[1952116493] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1922; }","duration":"206.595ms","start":"2026-03-14T01:35:20.098715Z","end":"2026-03-14T01:35:20.30531Z","steps":["trace[1952116493] 'read index received' (duration: 163.080165ms)","trace[1952116493] 'applied index is now lower than readState.Index' (duration: 43.513715ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:35:20.3054Z","caller":"traceutil/trace.go:171","msg":"trace[1900168072] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"405.169112ms","start":"2026-03-14T01:35:19.900217Z","end":"2026-03-14T01:35:20.305387Z","steps":["trace[1900168072] 'process raft request' (duration: 404.919637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:20.305333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:19.899855Z","time spent":"405.420858ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:35:20.305483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:19.900205Z","time spent":"405.238004ms","remote":"127.0.0.1:55086","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-14T01:35:20.30558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.52756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-14T01:35:20.305636Z","caller":"traceutil/trace.go:171","msg":"trace[90366585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1847; }","duration":"370.618611ms","start":"2026-03-14T01:35:19.935001Z","end":"2026-03-14T01:35:20.30562Z","steps":["trace[90366585] 'agreement among raft nodes before linearized reading' (duration: 370.415808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:20.305676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:19.934984Z","time spent":"370.684903ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T01:35:20.34179Z","caller":"traceutil/trace.go:171","msg":"trace[596600520] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"242.227641ms","start":"2026-03-14T01:35:20.099535Z","end":"2026-03-14T01:35:20.341762Z","steps":["trace[596600520] 'process raft request' (duration: 242.054478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:35:20.341833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.93838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:35:20.341891Z","caller":"traceutil/trace.go:171","msg":"trace[1895115653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"233.032942ms","start":"2026-03-14T01:35:20.108842Z","end":"2026-03-14T01:35:20.341875Z","steps":["trace[1895115653] 'agreement among raft nodes before linearized reading' (duration: 232.888699ms)"],"step_count":1}