{"level":"warn","ts":"2026-03-13T09:47:38.942318Z","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-13T09:47:38.942437Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.30:2380","--initial-cluster=instance=https://199.19.213.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.30: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-13T09:47:38.94254Z","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-13T09:47:38.942556Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-03-13T09:47:38.942579Z","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-13T09:47:38.943583Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"]} {"level":"info","ts":"2026-03-13T09:47:38.943687Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.30: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-13T09:47:38.948539Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.578638ms"} {"level":"info","ts":"2026-03-13T09:47:38.959616Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2b5783e11defe7e","cluster-id":"5210a9231a779bf0"} {"level":"info","ts":"2026-03-13T09:47:38.960178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T09:47:38.960252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 0"} {"level":"info","ts":"2026-03-13T09:47:38.960328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2b5783e11defe7e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T09:47:38.960373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 1"} {"level":"info","ts":"2026-03-13T09:47:38.960475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"warn","ts":"2026-03-13T09:47:38.969339Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T09:47:38.970954Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T09:47:38.972003Z","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-13T09:47:38.973957Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2b5783e11defe7e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T09:47:38.975897Z","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-13T09:47:38.976144Z","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-13T09:47:38.976179Z","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-13T09:47:38.976312Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2b5783e11defe7e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T09:47:38.976877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"info","ts":"2026-03-13T09:47:38.977115Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","added-peer-id":"e2b5783e11defe7e","added-peer-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-03-13T09:47:38.980236Z","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-13T09:47:38.980475Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2b5783e11defe7e","initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T09:47:38.980561Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T09:47:38.980555Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-03-13T09:47:38.980695Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-03-13T09:47:39.661202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T09:47:39.661279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T09:47:39.661311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgPreVoteResp from e2b5783e11defe7e at term 1"} {"level":"info","ts":"2026-03-13T09:47:39.661328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became candidate at term 2"} {"level":"info","ts":"2026-03-13T09:47:39.661339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgVoteResp from e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-03-13T09:47:39.66135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became leader at term 2"} {"level":"info","ts":"2026-03-13T09:47:39.661362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2b5783e11defe7e elected leader e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-03-13T09:47:39.662139Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:47:39.662853Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2b5783e11defe7e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.30:2379]}","request-path":"/0/members/e2b5783e11defe7e/attributes","cluster-id":"5210a9231a779bf0","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T09:47:39.662986Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:47:39.663065Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:47:39.663377Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:47:39.663473Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:47:39.663505Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:47:39.665228Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T09:47:39.665254Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T09:47:39.665276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.30:2379"} {"level":"info","ts":"2026-03-13T09:47:39.66651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-13T09:47:44.442659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267246908822059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T09:47:44.939062Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.005915136s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-13T09:47:45.951545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011763783s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:47:45.951659Z","caller":"traceutil/trace.go:171","msg":"trace[1137069456] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:236; }","duration":"2.009422169s","start":"2026-03-13T09:47:43.942221Z","end":"2026-03-13T09:47:45.951643Z","steps":["trace[1137069456] 'read index received' (duration: 997.106541ms)","trace[1137069456] 'applied index is now lower than readState.Index' (duration: 1.012314018s)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:47:45.951726Z","caller":"traceutil/trace.go:171","msg":"trace[1316236907] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"2.018673665s","start":"2026-03-13T09:47:43.933043Z","end":"2026-03-13T09:47:45.951717Z","steps":["trace[1316236907] 'process raft request' (duration: 1.006274062s)","trace[1316236907] 'compare' (duration: 1.011195597s)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:47:45.951772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:47:43.933019Z","time spent":"2.018729628s","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:47:45.952008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.009788836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-13T09:47:45.952036Z","caller":"traceutil/trace.go:171","msg":"trace[1735330138] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:231; }","duration":"2.009844539s","start":"2026-03-13T09:47:43.942183Z","end":"2026-03-13T09:47:45.952027Z","steps":["trace[1735330138] 'agreement among raft nodes before linearized reading' (duration: 2.009732284s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:45.952064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:47:43.942173Z","time spent":"2.009885291s","remote":"127.0.0.1:37936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-13T09:47:46.037932Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.098101106s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-13T09:47:46.03841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.772218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T09:47:46.038402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.979846519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:47:46.038484Z","caller":"traceutil/trace.go:171","msg":"trace[531432744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:232; }","duration":"1.979929333s","start":"2026-03-13T09:47:44.058525Z","end":"2026-03-13T09:47:46.038454Z","steps":["trace[531432744] 'agreement among raft nodes before linearized reading' (duration: 1.979767356s)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:47:46.038457Z","caller":"traceutil/trace.go:171","msg":"trace[608164758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:232; }","duration":"481.871662ms","start":"2026-03-13T09:47:45.55657Z","end":"2026-03-13T09:47:46.038441Z","steps":["trace[608164758] 'agreement among raft nodes before linearized reading' (duration: 481.734016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:46.038534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:47:44.058472Z","time spent":"1.980053788s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T09:47:46.038538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:47:45.556548Z","time spent":"481.978677ms","remote":"127.0.0.1:38076","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-13T09:47:46.038778Z","caller":"traceutil/trace.go:171","msg":"trace[1021652631] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"1.510978202s","start":"2026-03-13T09:47:44.527787Z","end":"2026-03-13T09:47:46.038766Z","steps":["trace[1021652631] 'process raft request' (duration: 1.510370294s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:46.038835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.608003ms","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-13T09:47:46.038859Z","caller":"traceutil/trace.go:171","msg":"trace[425521824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:232; }","duration":"636.659206ms","start":"2026-03-13T09:47:45.402192Z","end":"2026-03-13T09:47:46.038852Z","steps":["trace[425521824] 'agreement among raft nodes before linearized reading' (duration: 636.551581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:46.038879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:47:45.402178Z","time spent":"636.695377ms","remote":"127.0.0.1:38076","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-13T09:47:46.038868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:47:44.527719Z","time spent":"1.511101368s","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:47:46.038955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.539697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:47:46.038977Z","caller":"traceutil/trace.go:171","msg":"trace[575933714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:232; }","duration":"657.60096ms","start":"2026-03-13T09:47:45.38137Z","end":"2026-03-13T09:47:46.038971Z","steps":["trace[575933714] 'agreement among raft nodes before linearized reading' (duration: 657.566709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:46.038995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:47:45.381349Z","time spent":"657.640652ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T09:47:52.020828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267246908822358,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T09:47:52.316434Z","caller":"traceutil/trace.go:171","msg":"trace[844543853] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"796.371405ms","start":"2026-03-13T09:47:51.520039Z","end":"2026-03-13T09:47:52.31641Z","steps":["trace[844543853] 'read index received' (duration: 796.259941ms)","trace[844543853] 'applied index is now lower than readState.Index' (duration: 110.204µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:47:52.316533Z","caller":"traceutil/trace.go:171","msg":"trace[418027744] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"1.065697192s","start":"2026-03-13T09:47:51.250828Z","end":"2026-03-13T09:47:52.316525Z","steps":["trace[418027744] 'process raft request' (duration: 1.065395969s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:52.316804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:47:51.250797Z","time spent":"1.065760075s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:47:52.316801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.342977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-13T09:47:52.316865Z","caller":"traceutil/trace.go:171","msg":"trace[1122199825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"248.445402ms","start":"2026-03-13T09:47:52.068399Z","end":"2026-03-13T09:47:52.316845Z","steps":["trace[1122199825] 'agreement among raft nodes before linearized reading' (duration: 248.349628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:52.316998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.52001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-13T09:47:52.317093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.044224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"warn","ts":"2026-03-13T09:47:52.31709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.886743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-13T09:47:52.317184Z","caller":"traceutil/trace.go:171","msg":"trace[200336154] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:384; }","duration":"797.126457ms","start":"2026-03-13T09:47:51.520009Z","end":"2026-03-13T09:47:52.317135Z","steps":["trace[200336154] 'agreement among raft nodes before linearized reading' (duration: 797.019853ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:47:52.31721Z","caller":"traceutil/trace.go:171","msg":"trace[2120201792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:384; }","duration":"239.573862ms","start":"2026-03-13T09:47:52.077443Z","end":"2026-03-13T09:47:52.317017Z","steps":["trace[2120201792] 'agreement among raft nodes before linearized reading' (duration: 239.52146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:52.317242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:47:51.519995Z","time spent":"797.231902ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5349,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-03-13T09:47:52.317251Z","caller":"traceutil/trace.go:171","msg":"trace[282215309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:384; }","duration":"239.061181ms","start":"2026-03-13T09:47:52.078156Z","end":"2026-03-13T09:47:52.317218Z","steps":["trace[282215309] 'agreement among raft nodes before linearized reading' (duration: 238.79982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:52.317133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.809985ms","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-13T09:47:52.317303Z","caller":"traceutil/trace.go:171","msg":"trace[1403431709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:384; }","duration":"247.997183ms","start":"2026-03-13T09:47:52.069297Z","end":"2026-03-13T09:47:52.317294Z","steps":["trace[1403431709] 'agreement among raft nodes before linearized reading' (duration: 247.793335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:52.550689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.073311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:47:52.550775Z","caller":"traceutil/trace.go:171","msg":"trace[319787847] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"229.810835ms","start":"2026-03-13T09:47:52.320951Z","end":"2026-03-13T09:47:52.550762Z","steps":["trace[319787847] 'process raft request' (duration: 48.594437ms)","trace[319787847] 'compare' (duration: 180.968727ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:47:52.551905Z","caller":"traceutil/trace.go:171","msg":"trace[950757585] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"230.134049ms","start":"2026-03-13T09:47:52.32175Z","end":"2026-03-13T09:47:52.551884Z","steps":["trace[950757585] 'process raft request' (duration: 230.043935ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:47:52.551988Z","caller":"traceutil/trace.go:171","msg":"trace[954710654] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"230.16826ms","start":"2026-03-13T09:47:52.32175Z","end":"2026-03-13T09:47:52.551918Z","steps":["trace[954710654] 'process raft request' (duration: 229.943211ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:47:52.552086Z","caller":"traceutil/trace.go:171","msg":"trace[557542908] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"229.749013ms","start":"2026-03-13T09:47:52.322323Z","end":"2026-03-13T09:47:52.552072Z","steps":["trace[557542908] 'process raft request' (duration: 229.518453ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:47:52.552129Z","caller":"traceutil/trace.go:171","msg":"trace[1048042109] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"224.509499ms","start":"2026-03-13T09:47:52.327603Z","end":"2026-03-13T09:47:52.552113Z","steps":["trace[1048042109] 'process raft request' (duration: 224.367703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:47:57.660676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.233189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:47:57.660764Z","caller":"traceutil/trace.go:171","msg":"trace[1325389276] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"179.35726ms","start":"2026-03-13T09:47:57.481394Z","end":"2026-03-13T09:47:57.660751Z","steps":["trace[1325389276] 'process raft request' (duration: 43.997906ms)","trace[1325389276] 'compare' (duration: 135.133645ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:47:57.661385Z","caller":"traceutil/trace.go:171","msg":"trace[514216801] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"167.897305ms","start":"2026-03-13T09:47:57.493464Z","end":"2026-03-13T09:47:57.661361Z","steps":["trace[514216801] 'process raft request' (duration: 167.621014ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:47:57.66176Z","caller":"traceutil/trace.go:171","msg":"trace[1497336072] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"158.718702ms","start":"2026-03-13T09:47:57.503021Z","end":"2026-03-13T09:47:57.66174Z","steps":["trace[1497336072] 'process raft request' (duration: 158.66973ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:47:57.66196Z","caller":"traceutil/trace.go:171","msg":"trace[133657041] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"168.304481ms","start":"2026-03-13T09:47:57.493646Z","end":"2026-03-13T09:47:57.661951Z","steps":["trace[133657041] 'process raft request' (duration: 167.926055ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:47:57.662047Z","caller":"traceutil/trace.go:171","msg":"trace[151910310] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"168.441896ms","start":"2026-03-13T09:47:57.493593Z","end":"2026-03-13T09:47:57.662034Z","steps":["trace[151910310] 'process raft request' (duration: 167.662634ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:47:57.662071Z","caller":"traceutil/trace.go:171","msg":"trace[1064661972] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"159.207881ms","start":"2026-03-13T09:47:57.502855Z","end":"2026-03-13T09:47:57.662063Z","steps":["trace[1064661972] 'process raft request' (duration: 158.788684ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:48:02.705115Z","caller":"traceutil/trace.go:171","msg":"trace[2092617512] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"111.822054ms","start":"2026-03-13T09:48:02.59325Z","end":"2026-03-13T09:48:02.705072Z","steps":["trace[2092617512] 'read index received' (duration: 111.71017ms)","trace[2092617512] 'applied index is now lower than readState.Index' (duration: 110.544µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:48:02.705477Z","caller":"traceutil/trace.go:171","msg":"trace[98298009] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"142.491838ms","start":"2026-03-13T09:48:02.562973Z","end":"2026-03-13T09:48:02.705465Z","steps":["trace[98298009] 'process raft request' (duration: 141.968338ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:02.705757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.494581ms","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-13T09:48:02.705796Z","caller":"traceutil/trace.go:171","msg":"trace[303876340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"112.576504ms","start":"2026-03-13T09:48:02.59321Z","end":"2026-03-13T09:48:02.705787Z","steps":["trace[303876340] 'agreement among raft nodes before linearized reading' (duration: 112.440259ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:02.705948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.708825ms","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-13T09:48:02.705982Z","caller":"traceutil/trace.go:171","msg":"trace[910514587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:444; }","duration":"108.778197ms","start":"2026-03-13T09:48:02.597196Z","end":"2026-03-13T09:48:02.705974Z","steps":["trace[910514587] 'agreement among raft nodes before linearized reading' (duration: 108.726105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:02.706094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.741372ms","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-13T09:48:02.706127Z","caller":"traceutil/trace.go:171","msg":"trace[1257006350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:444; }","duration":"103.812276ms","start":"2026-03-13T09:48:02.602306Z","end":"2026-03-13T09:48:02.706118Z","steps":["trace[1257006350] 'agreement among raft nodes before linearized reading' (duration: 103.765294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:02.706274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.186814ms","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-13T09:48:02.706301Z","caller":"traceutil/trace.go:171","msg":"trace[526212894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:444; }","duration":"108.229415ms","start":"2026-03-13T09:48:02.598064Z","end":"2026-03-13T09:48:02.706293Z","steps":["trace[526212894] 'agreement among raft nodes before linearized reading' (duration: 108.182854ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:48:03.127457Z","caller":"traceutil/trace.go:171","msg":"trace[1559781460] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"418.029852ms","start":"2026-03-13T09:48:02.709401Z","end":"2026-03-13T09:48:03.127431Z","steps":["trace[1559781460] 'process raft request' (duration: 416.515965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:03.127669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:02.709388Z","time spent":"418.198638ms","remote":"127.0.0.1:38076","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-13T09:48:03.132666Z","caller":"traceutil/trace.go:171","msg":"trace[1892668703] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:459; }","duration":"382.045752ms","start":"2026-03-13T09:48:02.750605Z","end":"2026-03-13T09:48:03.132651Z","steps":["trace[1892668703] 'read index received' (duration: 375.382387ms)","trace[1892668703] 'applied index is now lower than readState.Index' (duration: 6.662555ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:48:03.132687Z","caller":"traceutil/trace.go:171","msg":"trace[1482676881] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"422.994061ms","start":"2026-03-13T09:48:02.709674Z","end":"2026-03-13T09:48:03.132668Z","steps":["trace[1482676881] 'process raft request' (duration: 422.839666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:03.133831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:02.709666Z","time spent":"424.110784ms","remote":"127.0.0.1:38076","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-13T09:48:03.132716Z","caller":"traceutil/trace.go:171","msg":"trace[1646046601] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"422.285225ms","start":"2026-03-13T09:48:02.710412Z","end":"2026-03-13T09:48:03.132697Z","steps":["trace[1646046601] 'process raft request' (duration: 422.17425ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:48:03.132771Z","caller":"traceutil/trace.go:171","msg":"trace[1379845113] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"421.266495ms","start":"2026-03-13T09:48:02.711496Z","end":"2026-03-13T09:48:03.132762Z","steps":["trace[1379845113] 'process raft request' (duration: 421.12491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:03.132852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.216798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-13T09:48:03.134955Z","caller":"traceutil/trace.go:171","msg":"trace[1906403736] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:448; }","duration":"384.387231ms","start":"2026-03-13T09:48:02.750554Z","end":"2026-03-13T09:48:03.134941Z","steps":["trace[1906403736] 'agreement among raft nodes before linearized reading' (duration: 382.225259ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:03.134592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:02.710388Z","time spent":"424.159295ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:48:03.134692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:02.711488Z","time spent":"423.162097ms","remote":"127.0.0.1:38076","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-13T09:48:03.135158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:02.750533Z","time spent":"384.571048ms","remote":"127.0.0.1:37858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.30\" "} {"level":"warn","ts":"2026-03-13T09:48:08.266166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.177924ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T09:48:11.485554Z","caller":"traceutil/trace.go:171","msg":"trace[1971119627] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"317.796546ms","start":"2026-03-13T09:48:11.167719Z","end":"2026-03-13T09:48:11.485516Z","steps":["trace[1971119627] 'read index received' (duration: 317.673532ms)","trace[1971119627] 'applied index is now lower than readState.Index' (duration: 121.864µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:48:11.48577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.939154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-13T09:48:11.485811Z","caller":"traceutil/trace.go:171","msg":"trace[828658160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"313.032068ms","start":"2026-03-13T09:48:11.172767Z","end":"2026-03-13T09:48:11.485799Z","steps":["trace[828658160] 'agreement among raft nodes before linearized reading' (duration: 312.914434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:11.485817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.671609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"warn","ts":"2026-03-13T09:48:11.485838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:11.172749Z","time spent":"313.083049ms","remote":"127.0.0.1:38076","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":"info","ts":"2026-03-13T09:48:11.485858Z","caller":"traceutil/trace.go:171","msg":"trace[1578249727] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:498; }","duration":"225.746192ms","start":"2026-03-13T09:48:11.2601Z","end":"2026-03-13T09:48:11.485847Z","steps":["trace[1578249727] 'agreement among raft nodes before linearized reading' (duration: 225.649998ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:48:11.485849Z","caller":"traceutil/trace.go:171","msg":"trace[1636656033] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"322.294255ms","start":"2026-03-13T09:48:11.163348Z","end":"2026-03-13T09:48:11.485642Z","steps":["trace[1636656033] 'process raft request' (duration: 322.033866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:11.48606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.636124ms","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-13T09:48:11.486052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:11.16333Z","time spent":"322.616286ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:48:11.486093Z","caller":"traceutil/trace.go:171","msg":"trace[1914361689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"312.689786ms","start":"2026-03-13T09:48:11.173396Z","end":"2026-03-13T09:48:11.486086Z","steps":["trace[1914361689] 'agreement among raft nodes before linearized reading' (duration: 312.634834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:11.486116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:11.173388Z","time spent":"312.722427ms","remote":"127.0.0.1:38076","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-13T09:48:11.486227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.506031ms","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-13T09:48:11.486311Z","caller":"traceutil/trace.go:171","msg":"trace[1596569692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"318.581244ms","start":"2026-03-13T09:48:11.167689Z","end":"2026-03-13T09:48:11.48627Z","steps":["trace[1596569692] 'agreement among raft nodes before linearized reading' (duration: 318.507311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:11.486334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:11.167676Z","time spent":"318.650207ms","remote":"127.0.0.1:38076","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-13T09:48:14.018355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267246908822598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T09:48:14.51889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267246908822598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T09:48:14.720993Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.227657767s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T09:48:14.72141Z","caller":"traceutil/trace.go:171","msg":"trace[1080651147] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"1.203459147s","start":"2026-03-13T09:48:13.517932Z","end":"2026-03-13T09:48:14.721391Z","steps":["trace[1080651147] 'read index received' (duration: 1.203199978s)","trace[1080651147] 'applied index is now lower than readState.Index' (duration: 258.569µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:48:14.721456Z","caller":"traceutil/trace.go:171","msg":"trace[1097598336] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.228186745s","start":"2026-03-13T09:48:13.493255Z","end":"2026-03-13T09:48:14.721442Z","steps":["trace[1097598336] 'process raft request' (duration: 1.228008389s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:14.721572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:13.493232Z","time spent":"1.228254767s","remote":"127.0.0.1:38076","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-13T09:48:14.721613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.330992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:48:14.721603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198333919s","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-13T09:48:14.721673Z","caller":"traceutil/trace.go:171","msg":"trace[2083514927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"858.441445ms","start":"2026-03-13T09:48:13.863217Z","end":"2026-03-13T09:48:14.721659Z","steps":["trace[2083514927] 'agreement among raft nodes before linearized reading' (duration: 858.312131ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:48:14.721679Z","caller":"traceutil/trace.go:171","msg":"trace[1233617476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:505; }","duration":"1.198446973s","start":"2026-03-13T09:48:13.523217Z","end":"2026-03-13T09:48:14.721664Z","steps":["trace[1233617476] 'agreement among raft nodes before linearized reading' (duration: 1.19835214s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:14.721671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198580698s","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-13T09:48:14.721706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:13.863199Z","time spent":"858.501848ms","remote":"127.0.0.1:37828","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-13T09:48:14.721723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:13.523194Z","time spent":"1.198516825s","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T09:48:14.721734Z","caller":"traceutil/trace.go:171","msg":"trace[209515315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:505; }","duration":"1.198682331s","start":"2026-03-13T09:48:13.523034Z","end":"2026-03-13T09:48:14.721716Z","steps":["trace[209515315] 'agreement among raft nodes before linearized reading' (duration: 1.198487405s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:14.721775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:13.52302Z","time spent":"1.198747873s","remote":"127.0.0.1:38076","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-13T09:48:14.721801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203594421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-13T09:48:14.721846Z","caller":"traceutil/trace.go:171","msg":"trace[1135759274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"1.203942383s","start":"2026-03-13T09:48:13.517894Z","end":"2026-03-13T09:48:14.721836Z","steps":["trace[1135759274] 'agreement among raft nodes before linearized reading' (duration: 1.203589771s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:14.721871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:13.517878Z","time spent":"1.203988535s","remote":"127.0.0.1:38076","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-13T09:48:19.236458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267246908822663,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T09:48:19.639386Z","caller":"traceutil/trace.go:171","msg":"trace[1346232429] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"903.784367ms","start":"2026-03-13T09:48:18.735582Z","end":"2026-03-13T09:48:19.639367Z","steps":["trace[1346232429] 'read index received' (duration: 903.589121ms)","trace[1346232429] 'applied index is now lower than readState.Index' (duration: 194.326µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:48:19.63947Z","caller":"traceutil/trace.go:171","msg":"trace[233553911] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"999.871481ms","start":"2026-03-13T09:48:18.639564Z","end":"2026-03-13T09:48:19.639436Z","steps":["trace[233553911] 'process raft request' (duration: 999.677275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:19.639587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.012894ms","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-13T09:48:19.639623Z","caller":"traceutil/trace.go:171","msg":"trace[884461707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"904.102887ms","start":"2026-03-13T09:48:18.735508Z","end":"2026-03-13T09:48:19.639611Z","steps":["trace[884461707] 'agreement among raft nodes before linearized reading' (duration: 903.970693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:19.639629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:18.639545Z","time spent":"1.000007816s","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:48:19.639649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:18.735483Z","time spent":"904.159629ms","remote":"127.0.0.1:38076","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-13T09:48:19.683622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.211894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:48:19.684056Z","caller":"traceutil/trace.go:171","msg":"trace[889181590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"820.757251ms","start":"2026-03-13T09:48:18.863282Z","end":"2026-03-13T09:48:19.684039Z","steps":["trace[889181590] 'agreement among raft nodes before linearized reading' (duration: 776.613541ms)","trace[889181590] 'range keys from in-memory index tree' (duration: 43.645983ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:48:19.684091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:18.863259Z","time spent":"820.821924ms","remote":"127.0.0.1:37832","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-13T09:48:19.683715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.016006ms","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-13T09:48:19.684235Z","caller":"traceutil/trace.go:171","msg":"trace[1222250654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"931.532684ms","start":"2026-03-13T09:48:18.752666Z","end":"2026-03-13T09:48:19.684199Z","steps":["trace[1222250654] 'agreement among raft nodes before linearized reading' (duration: 887.237389ms)","trace[1222250654] 'range keys from in-memory index tree' (duration: 43.691775ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:48:19.683826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.88816ms","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-13T09:48:19.684362Z","caller":"traceutil/trace.go:171","msg":"trace[259605576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"937.436808ms","start":"2026-03-13T09:48:18.746907Z","end":"2026-03-13T09:48:19.684344Z","steps":["trace[259605576] 'agreement among raft nodes before linearized reading' (duration: 892.961028ms)","trace[259605576] 'range keys from in-memory index tree' (duration: 43.83425ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:48:19.684394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:18.746849Z","time spent":"937.536412ms","remote":"127.0.0.1:38076","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-13T09:48:19.683826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.999508ms","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-13T09:48:19.6845Z","caller":"traceutil/trace.go:171","msg":"trace[962923894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"932.722042ms","start":"2026-03-13T09:48:18.751766Z","end":"2026-03-13T09:48:19.684489Z","steps":["trace[962923894] 'agreement among raft nodes before linearized reading' (duration: 888.145628ms)","trace[962923894] 'range keys from in-memory index tree' (duration: 43.792529ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:48:19.684529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:18.75175Z","time spent":"932.770954ms","remote":"127.0.0.1:38076","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-13T09:48:19.684457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:18.752658Z","time spent":"931.674628ms","remote":"127.0.0.1:38076","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-13T09:48:21.26233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400239061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:48:21.262386Z","caller":"traceutil/trace.go:171","msg":"trace[1414538479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"1.400330574s","start":"2026-03-13T09:48:19.86204Z","end":"2026-03-13T09:48:21.26237Z","steps":["trace[1414538479] 'range keys from in-memory index tree' (duration: 1.400145367s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:21.262409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:19.862023Z","time spent":"1.400381066s","remote":"127.0.0.1:37828","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-13T09:48:22.220909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267246908822674,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T09:48:22.722004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338267246908822674,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T09:48:22.84109Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.149172354s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T09:48:22.841363Z","caller":"traceutil/trace.go:171","msg":"trace[1541139975] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"1.121274103s","start":"2026-03-13T09:48:21.720073Z","end":"2026-03-13T09:48:22.841348Z","steps":["trace[1541139975] 'read index received' (duration: 1.121131329s)","trace[1541139975] 'applied index is now lower than readState.Index' (duration: 142.234µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:48:22.841584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12148782s","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-13T09:48:22.841648Z","caller":"traceutil/trace.go:171","msg":"trace[437957780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"1.121611205s","start":"2026-03-13T09:48:21.720021Z","end":"2026-03-13T09:48:22.841632Z","steps":["trace[437957780] 'agreement among raft nodes before linearized reading' (duration: 1.121411058s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:22.841667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111421215s","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-13T09:48:22.84169Z","caller":"traceutil/trace.go:171","msg":"trace[634453205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"1.111483587s","start":"2026-03-13T09:48:21.730199Z","end":"2026-03-13T09:48:22.841683Z","steps":["trace[634453205] 'agreement among raft nodes before linearized reading' (duration: 1.111386554s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:22.841683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:21.719999Z","time spent":"1.121677166s","remote":"127.0.0.1:38076","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-13T09:48:22.84171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:21.730183Z","time spent":"1.111521718s","remote":"127.0.0.1:38076","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-13T09:48:22.841968Z","caller":"traceutil/trace.go:171","msg":"trace[520888309] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.150168165s","start":"2026-03-13T09:48:21.691792Z","end":"2026-03-13T09:48:22.84196Z","steps":["trace[520888309] 'process raft request' (duration: 1.149450452s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:22.842023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:21.691774Z","time spent":"1.150208217s","remote":"127.0.0.1:38076","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-13T09:48:22.842032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111654933s","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-13T09:48:22.842145Z","caller":"traceutil/trace.go:171","msg":"trace[1788917180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"1.111829668s","start":"2026-03-13T09:48:21.730289Z","end":"2026-03-13T09:48:22.842119Z","steps":["trace[1788917180] 'agreement among raft nodes before linearized reading' (duration: 1.111542709s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:22.842207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:21.730267Z","time spent":"1.111925081s","remote":"127.0.0.1:38076","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-13T09:48:25.216442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.631185ms","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-13T09:48:25.216531Z","caller":"traceutil/trace.go:171","msg":"trace[1245539872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"351.757329ms","start":"2026-03-13T09:48:24.864752Z","end":"2026-03-13T09:48:25.21651Z","steps":["trace[1245539872] 'range keys from in-memory index tree' (duration: 351.478691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:25.216483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.250618ms","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-13T09:48:25.216536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.674318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:48:25.21658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:24.864739Z","time spent":"351.830811ms","remote":"127.0.0.1:38076","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-13T09:48:25.216619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.293531ms","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-13T09:48:25.21666Z","caller":"traceutil/trace.go:171","msg":"trace[709064917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"349.354894ms","start":"2026-03-13T09:48:24.867293Z","end":"2026-03-13T09:48:25.216648Z","steps":["trace[709064917] 'range keys from in-memory index tree' (duration: 349.209079ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:48:25.216614Z","caller":"traceutil/trace.go:171","msg":"trace[531721507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"352.801722ms","start":"2026-03-13T09:48:24.863793Z","end":"2026-03-13T09:48:25.216595Z","steps":["trace[531721507] 'range keys from in-memory index tree' (duration: 352.578375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:25.21669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:24.867281Z","time spent":"349.402555ms","remote":"127.0.0.1:38076","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-13T09:48:25.216563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.665794ms","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-13T09:48:25.216767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:24.863772Z","time spent":"352.982358ms","remote":"127.0.0.1:37832","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-13T09:48:25.216606Z","caller":"traceutil/trace.go:171","msg":"trace[1459101097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"369.423934ms","start":"2026-03-13T09:48:24.847158Z","end":"2026-03-13T09:48:25.216582Z","steps":["trace[1459101097] 'range keys from in-memory index tree' (duration: 369.020552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:25.216954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:24.847141Z","time spent":"369.802276ms","remote":"127.0.0.1:38076","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-13T09:48:25.217106Z","caller":"traceutil/trace.go:171","msg":"trace[933480368] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"344.390727ms","start":"2026-03-13T09:48:24.8727Z","end":"2026-03-13T09:48:25.217091Z","steps":["trace[933480368] 'process raft request' (duration: 344.288804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:25.217227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:24.872691Z","time spent":"344.449329ms","remote":"127.0.0.1:37880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T09:48:25.217464Z","caller":"traceutil/trace.go:171","msg":"trace[126456849] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:593; }","duration":"149.093856ms","start":"2026-03-13T09:48:25.068342Z","end":"2026-03-13T09:48:25.217436Z","steps":["trace[126456849] 'read index received' (duration: 148.770996ms)","trace[126456849] 'applied index is now lower than readState.Index' (duration: 319.46µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:48:25.216909Z","caller":"traceutil/trace.go:171","msg":"trace[115939019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"350.030804ms","start":"2026-03-13T09:48:24.866862Z","end":"2026-03-13T09:48:25.216893Z","steps":["trace[115939019] 'range keys from in-memory index tree' (duration: 349.56946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:48:25.217796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.451857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:48:25.217788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:48:24.866847Z","time spent":"350.922512ms","remote":"127.0.0.1:38076","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-13T09:48:25.217845Z","caller":"traceutil/trace.go:171","msg":"trace[1810997111] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:573; }","duration":"149.54959ms","start":"2026-03-13T09:48:25.068281Z","end":"2026-03-13T09:48:25.217831Z","steps":["trace[1810997111] 'agreement among raft nodes before linearized reading' (duration: 149.356274ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:49:56.4287Z","caller":"traceutil/trace.go:171","msg":"trace[752356589] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"752.926381ms","start":"2026-03-13T09:49:55.675745Z","end":"2026-03-13T09:49:56.428671Z","steps":["trace[752356589] 'process raft request' (duration: 752.731647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:49:56.428867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:49:55.675727Z","time spent":"753.036152ms","remote":"127.0.0.1:38076","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-13T09:49:56.429015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.929327ms","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-13T09:49:56.429101Z","caller":"traceutil/trace.go:171","msg":"trace[1220825337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"749.0717ms","start":"2026-03-13T09:49:55.680006Z","end":"2026-03-13T09:49:56.429078Z","steps":["trace[1220825337] 'agreement among raft nodes before linearized reading' (duration: 748.815295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:49:56.429201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:49:55.679981Z","time spent":"749.166252ms","remote":"127.0.0.1:38076","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-13T09:49:56.429286Z","caller":"traceutil/trace.go:171","msg":"trace[211138831] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1033; }","duration":"748.550881ms","start":"2026-03-13T09:49:55.680077Z","end":"2026-03-13T09:49:56.428627Z","steps":["trace[211138831] 'read index received' (duration: 7.281263ms)","trace[211138831] 'applied index is now lower than readState.Index' (duration: 741.268358ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:49:56.429587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.810979ms","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-13T09:49:56.429632Z","caller":"traceutil/trace.go:171","msg":"trace[1715535103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"746.89948ms","start":"2026-03-13T09:49:55.68272Z","end":"2026-03-13T09:49:56.429619Z","steps":["trace[1715535103] 'agreement among raft nodes before linearized reading' (duration: 746.706486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:49:56.429669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:49:55.682713Z","time spent":"746.945472ms","remote":"127.0.0.1:38076","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-13T09:49:56.429822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.675205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:49:56.429876Z","caller":"traceutil/trace.go:171","msg":"trace[1275604942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"567.761857ms","start":"2026-03-13T09:49:55.862098Z","end":"2026-03-13T09:49:56.429859Z","steps":["trace[1275604942] 'agreement among raft nodes before linearized reading' (duration: 567.668956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:49:56.43018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:49:55.862078Z","time spent":"568.088663ms","remote":"127.0.0.1:37828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}