{"level":"warn","ts":"2026-03-03T19:57:50.834957Z","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-03T19:57:50.835067Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-03T19:57:50.83514Z","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-03T19:57:50.835154Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-03-03T19:57:50.835172Z","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-03T19:57:50.835859Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-03-03T19:57:50.835945Z","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.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-03T19:57:50.841009Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.813761ms"} {"level":"info","ts":"2026-03-03T19:57:50.847959Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-03-03T19:57:50.848089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T19:57:50.848127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-03-03T19:57:50.848149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T19:57:50.848165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-03-03T19:57:50.848227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-03-03T19:57:50.854501Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T19:57:50.855754Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T19:57:50.85765Z","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-03T19:57:50.859841Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T19:57:50.861484Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T19:57:50.861536Z","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-03T19:57:50.862931Z","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-03T19:57:50.862999Z","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-03T19:57:50.863813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-03-03T19:57:50.864098Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-03-03T19:57:50.866457Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-03-03T19:57:50.866444Z","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-03T19:57:50.866494Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-03-03T19:57:50.866746Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T19:57:50.866781Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T19:57:51.64914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T19:57:51.649283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T19:57:51.649311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-03-03T19:57:51.649331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-03-03T19:57:51.649342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-03-03T19:57:51.649365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-03-03T19:57:51.649379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-03-03T19:57:51.671971Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T19:57:51.672117Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T19:57:51.672278Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T19:57:51.672327Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T19:57:51.67218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T19:57:51.672609Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T19:57:51.67411Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T19:57:51.674291Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T19:57:51.674342Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T19:57:51.675916Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-03-03T19:57:51.677836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T19:57:54.281262Z","caller":"traceutil/trace.go:171","msg":"trace[1611227444] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"437.547054ms","start":"2026-03-03T19:57:53.843687Z","end":"2026-03-03T19:57:54.281234Z","steps":["trace[1611227444] 'read index received' (duration: 437.364303ms)","trace[1611227444] 'applied index is now lower than readState.Index' (duration: 182.111µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:57:54.281323Z","caller":"traceutil/trace.go:171","msg":"trace[80777495] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"439.210519ms","start":"2026-03-03T19:57:53.842091Z","end":"2026-03-03T19:57:54.281302Z","steps":["trace[80777495] 'process raft request' (duration: 439.012057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:54.281443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.748506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T19:57:54.28149Z","caller":"traceutil/trace.go:171","msg":"trace[1682260236] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"437.849541ms","start":"2026-03-03T19:57:53.843632Z","end":"2026-03-03T19:57:54.281481Z","steps":["trace[1682260236] 'agreement among raft nodes before linearized reading' (duration: 437.742435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:54.281511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:53.843612Z","time spent":"437.893564ms","remote":"127.0.0.1:33234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-03-03T19:57:54.281568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.619217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T19:57:54.281664Z","caller":"traceutil/trace.go:171","msg":"trace[1429682193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"339.729513ms","start":"2026-03-03T19:57:53.94191Z","end":"2026-03-03T19:57:54.28164Z","steps":["trace[1429682193] 'agreement among raft nodes before linearized reading' (duration: 339.534592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:54.281707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:53.941841Z","time spent":"339.857501ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T19:57:54.281951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:53.842081Z","time spent":"439.287004ms","remote":"127.0.0.1:33252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T19:57:54.78757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360655940354265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T19:57:55.31027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.974632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-03T19:57:55.310587Z","caller":"traceutil/trace.go:171","msg":"trace[1162052055] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"1.024368596s","start":"2026-03-03T19:57:54.286188Z","end":"2026-03-03T19:57:55.310557Z","steps":["trace[1162052055] 'process raft request' (duration: 540.751617ms)","trace[1162052055] 'compare' (duration: 482.818073ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:57:55.310823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:54.286172Z","time spent":"1.024572958s","remote":"127.0.0.1:33252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T19:57:55.311021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023491506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T19:57:55.311115Z","caller":"traceutil/trace.go:171","msg":"trace[2085918137] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"1.023592062s","start":"2026-03-03T19:57:54.287475Z","end":"2026-03-03T19:57:55.311067Z","steps":["trace[2085918137] 'agreement among raft nodes before linearized reading' (duration: 1.023231502s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:55.311161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:54.287459Z","time spent":"1.023692108s","remote":"127.0.0.1:33234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"info","ts":"2026-03-03T19:57:55.31065Z","caller":"traceutil/trace.go:171","msg":"trace[1859370202] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"1.022878262s","start":"2026-03-03T19:57:54.287513Z","end":"2026-03-03T19:57:55.310391Z","steps":["trace[1859370202] 'read index received' (duration: 539.459774ms)","trace[1859370202] 'applied index is now lower than readState.Index' (duration: 483.417037ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:57:55.812625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360655940354268,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T19:57:55.958429Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.085163479s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T19:57:55.963183Z","caller":"traceutil/trace.go:171","msg":"trace[1450810998] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"651.539795ms","start":"2026-03-03T19:57:55.311612Z","end":"2026-03-03T19:57:55.963152Z","steps":["trace[1450810998] 'read index received' (duration: 646.983513ms)","trace[1450810998] 'applied index is now lower than readState.Index' (duration: 4.555112ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:57:55.963348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426876691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T19:57:55.963349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.887651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T19:57:55.963384Z","caller":"traceutil/trace.go:171","msg":"trace[1369017918] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:74; }","duration":"1.426972437s","start":"2026-03-03T19:57:54.536404Z","end":"2026-03-03T19:57:55.963377Z","steps":["trace[1369017918] 'agreement among raft nodes before linearized reading' (duration: 1.426885662s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:55.963376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.312167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T19:57:55.963399Z","caller":"traceutil/trace.go:171","msg":"trace[550858695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"926.989487ms","start":"2026-03-03T19:57:55.036395Z","end":"2026-03-03T19:57:55.963384Z","steps":["trace[550858695] 'agreement among raft nodes before linearized reading' (duration: 926.828128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:55.963408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:54.536375Z","time spent":"1.42702827s","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-03-03T19:57:55.963411Z","caller":"traceutil/trace.go:171","msg":"trace[916106920] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:74; }","duration":"649.409953ms","start":"2026-03-03T19:57:55.313991Z","end":"2026-03-03T19:57:55.963401Z","steps":["trace[916106920] 'agreement among raft nodes before linearized reading' (duration: 649.329209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:55.963431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:55.036362Z","time spent":"927.063102ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T19:57:55.963418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.80882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T19:57:55.963451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:55.31397Z","time spent":"649.473307ms","remote":"127.0.0.1:33234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-03-03T19:57:55.963479Z","caller":"traceutil/trace.go:171","msg":"trace[984595170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:74; }","duration":"162.919356ms","start":"2026-03-03T19:57:55.800542Z","end":"2026-03-03T19:57:55.963461Z","steps":["trace[984595170] 'agreement among raft nodes before linearized reading' (duration: 162.791269ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:57:55.963235Z","caller":"traceutil/trace.go:171","msg":"trace[1016518823] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"1.090125933s","start":"2026-03-03T19:57:54.873082Z","end":"2026-03-03T19:57:55.963208Z","steps":["trace[1016518823] 'process raft request' (duration: 1.085623204s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:55.963697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:54.873057Z","time spent":"1.090605051s","remote":"127.0.0.1:33058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T19:57:55.96362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.537487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T19:57:55.963787Z","caller":"traceutil/trace.go:171","msg":"trace[1463825179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"622.738188ms","start":"2026-03-03T19:57:55.341035Z","end":"2026-03-03T19:57:55.963773Z","steps":["trace[1463825179] 'agreement among raft nodes before linearized reading' (duration: 622.518675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:55.963829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:55.341022Z","time spent":"622.796811ms","remote":"127.0.0.1:32882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T19:57:57.205519Z","caller":"traceutil/trace.go:171","msg":"trace[1816792129] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"103.797712ms","start":"2026-03-03T19:57:57.101685Z","end":"2026-03-03T19:57:57.205483Z","steps":["trace[1816792129] 'compare' (duration: 92.787343ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:57:58.387409Z","caller":"traceutil/trace.go:171","msg":"trace[1259074541] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"670.65005ms","start":"2026-03-03T19:57:57.716724Z","end":"2026-03-03T19:57:58.387374Z","steps":["trace[1259074541] 'process raft request' (duration: 670.492641ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:57:58.387792Z","caller":"traceutil/trace.go:171","msg":"trace[146360331] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:215; }","duration":"404.267377ms","start":"2026-03-03T19:57:57.983505Z","end":"2026-03-03T19:57:58.387772Z","steps":["trace[146360331] 'read index received' (duration: 404.212084ms)","trace[146360331] 'applied index is now lower than readState.Index' (duration: 53.953µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:57:58.387806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:57.716711Z","time spent":"670.792288ms","remote":"127.0.0.1:33056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T19:57:58.38795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.427066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T19:57:58.388074Z","caller":"traceutil/trace.go:171","msg":"trace[1823704886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:210; }","duration":"404.598526ms","start":"2026-03-03T19:57:57.983448Z","end":"2026-03-03T19:57:58.388046Z","steps":["trace[1823704886] 'agreement among raft nodes before linearized reading' (duration: 404.397784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:58.388139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:57:57.983426Z","time spent":"404.699672ms","remote":"127.0.0.1:33128","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\" "} 2026/03/03 19:57:59 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-03-03T19:57:59.860078Z","caller":"traceutil/trace.go:171","msg":"trace[1282651632] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"100.224538ms","start":"2026-03-03T19:57:59.759823Z","end":"2026-03-03T19:57:59.860047Z","steps":["trace[1282651632] 'process raft request' (duration: 100.128302ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:57:59.860309Z","caller":"traceutil/trace.go:171","msg":"trace[1359541086] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"100.879476ms","start":"2026-03-03T19:57:59.759398Z","end":"2026-03-03T19:57:59.860278Z","steps":["trace[1359541086] 'process raft request' (duration: 100.412829ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:57:59.967737Z","caller":"traceutil/trace.go:171","msg":"trace[865507000] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"103.604015ms","start":"2026-03-03T19:57:59.864103Z","end":"2026-03-03T19:57:59.967707Z","steps":["trace[865507000] 'process raft request' (duration: 43.426528ms)","trace[865507000] 'compare' (duration: 60.014678ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:57:59.968033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.423885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:57:59.968088Z","caller":"traceutil/trace.go:171","msg":"trace[1481652825] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:259; }","duration":"103.5161ms","start":"2026-03-03T19:57:59.864557Z","end":"2026-03-03T19:57:59.968073Z","steps":["trace[1481652825] 'agreement among raft nodes before linearized reading' (duration: 103.381593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:57:59.968411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.294226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-03-03T19:57:59.968513Z","caller":"traceutil/trace.go:171","msg":"trace[1826838405] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:259; }","duration":"104.451745ms","start":"2026-03-03T19:57:59.864045Z","end":"2026-03-03T19:57:59.968497Z","steps":["trace[1826838405] 'agreement among raft nodes before linearized reading' (duration: 103.895523ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:00.198358Z","caller":"traceutil/trace.go:171","msg":"trace[514629981] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"142.839079ms","start":"2026-03-03T19:58:00.055482Z","end":"2026-03-03T19:58:00.198321Z","steps":["trace[514629981] 'process raft request' (duration: 89.313169ms)","trace[514629981] 'compare' (duration: 53.333598ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:00.904575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.609781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T19:58:00.904785Z","caller":"traceutil/trace.go:171","msg":"trace[1869685050] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"154.29657ms","start":"2026-03-03T19:58:00.750457Z","end":"2026-03-03T19:58:00.904753Z","steps":["trace[1869685050] 'process raft request' (duration: 154.232096ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:00.904985Z","caller":"traceutil/trace.go:171","msg":"trace[198974752] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"154.6392ms","start":"2026-03-03T19:58:00.75027Z","end":"2026-03-03T19:58:00.904909Z","steps":["trace[198974752] 'process raft request' (duration: 53.597014ms)","trace[198974752] 'compare' (duration: 100.463452ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:01.267764Z","caller":"traceutil/trace.go:171","msg":"trace[1951309106] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"109.485984ms","start":"2026-03-03T19:58:01.158247Z","end":"2026-03-03T19:58:01.267733Z","steps":["trace[1951309106] 'process raft request' (duration: 38.695768ms)","trace[1951309106] 'compare' (duration: 70.393873ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:02.508339Z","caller":"traceutil/trace.go:171","msg":"trace[895431651] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"208.153542ms","start":"2026-03-03T19:58:02.300153Z","end":"2026-03-03T19:58:02.508306Z","steps":["trace[895431651] 'process raft request' (duration: 207.882166ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:02.694999Z","caller":"traceutil/trace.go:171","msg":"trace[1969253165] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"105.51994ms","start":"2026-03-03T19:58:02.589438Z","end":"2026-03-03T19:58:02.694958Z","steps":["trace[1969253165] 'process raft request' (duration: 101.933618ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:03.328772Z","caller":"traceutil/trace.go:171","msg":"trace[1289175451] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"118.234164ms","start":"2026-03-03T19:58:03.210507Z","end":"2026-03-03T19:58:03.328741Z","steps":["trace[1289175451] 'process raft request' (duration: 83.464725ms)","trace[1289175451] 'compare' (duration: 34.594639ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:03.328922Z","caller":"traceutil/trace.go:171","msg":"trace[1571641762] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"117.883093ms","start":"2026-03-03T19:58:03.21102Z","end":"2026-03-03T19:58:03.328903Z","steps":["trace[1571641762] 'process raft request' (duration: 117.688531ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:03.746267Z","caller":"traceutil/trace.go:171","msg":"trace[1358140318] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"136.981655ms","start":"2026-03-03T19:58:03.60926Z","end":"2026-03-03T19:58:03.746242Z","steps":["trace[1358140318] 'process raft request' (duration: 136.629734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:03.927038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.402971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T19:58:03.927144Z","caller":"traceutil/trace.go:171","msg":"trace[45216073] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"176.813684ms","start":"2026-03-03T19:58:03.750313Z","end":"2026-03-03T19:58:03.927127Z","steps":["trace[45216073] 'process raft request' (duration: 53.243054ms)","trace[45216073] 'compare' (duration: 123.260422ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:03.933754Z","caller":"traceutil/trace.go:171","msg":"trace[433048712] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:324; }","duration":"133.664728ms","start":"2026-03-03T19:58:03.800076Z","end":"2026-03-03T19:58:03.933741Z","steps":["trace[433048712] 'read index received' (duration: 3.413522ms)","trace[433048712] 'applied index is now lower than readState.Index' (duration: 130.250326ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:03.933939Z","caller":"traceutil/trace.go:171","msg":"trace[262827000] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"177.544637ms","start":"2026-03-03T19:58:03.75635Z","end":"2026-03-03T19:58:03.933894Z","steps":["trace[262827000] 'process raft request' (duration: 177.327654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:03.933997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.902553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-03T19:58:03.934065Z","caller":"traceutil/trace.go:171","msg":"trace[1236370938] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:312; }","duration":"134.03275ms","start":"2026-03-03T19:58:03.800018Z","end":"2026-03-03T19:58:03.934051Z","steps":["trace[1236370938] 'agreement among raft nodes before linearized reading' (duration: 133.895572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:04.00085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.574077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-03T19:58:04.000956Z","caller":"traceutil/trace.go:171","msg":"trace[213493704] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:313; }","duration":"100.715406ms","start":"2026-03-03T19:58:03.900203Z","end":"2026-03-03T19:58:04.000919Z","steps":["trace[213493704] 'agreement among raft nodes before linearized reading' (duration: 98.162985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:04.004481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.755468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-03T19:58:04.004612Z","caller":"traceutil/trace.go:171","msg":"trace[731735053] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:313; }","duration":"153.95468ms","start":"2026-03-03T19:58:03.850643Z","end":"2026-03-03T19:58:04.004597Z","steps":["trace[731735053] 'agreement among raft nodes before linearized reading' (duration: 149.785373ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.293429Z","caller":"traceutil/trace.go:171","msg":"trace[1070851277] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"207.172341ms","start":"2026-03-03T19:58:04.086216Z","end":"2026-03-03T19:58:04.293388Z","steps":["trace[1070851277] 'process raft request' (duration: 201.196326ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.293461Z","caller":"traceutil/trace.go:171","msg":"trace[460914586] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"204.497362ms","start":"2026-03-03T19:58:04.088921Z","end":"2026-03-03T19:58:04.293419Z","steps":["trace[460914586] 'process raft request' (duration: 204.360143ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.29352Z","caller":"traceutil/trace.go:171","msg":"trace[35094599] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:333; }","duration":"193.268494ms","start":"2026-03-03T19:58:04.100224Z","end":"2026-03-03T19:58:04.293493Z","steps":["trace[35094599] 'read index received' (duration: 19.752094ms)","trace[35094599] 'applied index is now lower than readState.Index' (duration: 173.51511ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:04.29363Z","caller":"traceutil/trace.go:171","msg":"trace[1234049133] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"204.580277ms","start":"2026-03-03T19:58:04.08904Z","end":"2026-03-03T19:58:04.29362Z","steps":["trace[1234049133] 'process raft request' (duration: 204.29813ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.29379Z","caller":"traceutil/trace.go:171","msg":"trace[68621136] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"203.668152ms","start":"2026-03-03T19:58:04.090078Z","end":"2026-03-03T19:58:04.293746Z","steps":["trace[68621136] 'process raft request' (duration: 203.363544ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.293884Z","caller":"traceutil/trace.go:171","msg":"trace[1212385034] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"204.580036ms","start":"2026-03-03T19:58:04.089287Z","end":"2026-03-03T19:58:04.293867Z","steps":["trace[1212385034] 'process raft request' (duration: 204.100928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:04.293935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.70319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-03T19:58:04.293991Z","caller":"traceutil/trace.go:171","msg":"trace[1821274522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:325; }","duration":"193.819767ms","start":"2026-03-03T19:58:04.100156Z","end":"2026-03-03T19:58:04.293976Z","steps":["trace[1821274522] 'agreement among raft nodes before linearized reading' (duration: 193.70249ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.319838Z","caller":"traceutil/trace.go:171","msg":"trace[34407205] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"197.190437ms","start":"2026-03-03T19:58:04.122617Z","end":"2026-03-03T19:58:04.319808Z","steps":["trace[34407205] 'process raft request' (duration: 196.941822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:04.320015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.284477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-03T19:58:04.320179Z","caller":"traceutil/trace.go:171","msg":"trace[504792489] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:326; }","duration":"120.520642ms","start":"2026-03-03T19:58:04.19964Z","end":"2026-03-03T19:58:04.320161Z","steps":["trace[504792489] 'agreement among raft nodes before linearized reading' (duration: 120.116998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:04.320117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.848473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-03T19:58:04.320409Z","caller":"traceutil/trace.go:171","msg":"trace[1273324833] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:326; }","duration":"170.345462ms","start":"2026-03-03T19:58:04.150036Z","end":"2026-03-03T19:58:04.320382Z","steps":["trace[1273324833] 'agreement among raft nodes before linearized reading' (duration: 169.786959ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.555406Z","caller":"traceutil/trace.go:171","msg":"trace[628011150] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"205.762085ms","start":"2026-03-03T19:58:04.349601Z","end":"2026-03-03T19:58:04.555363Z","steps":["trace[628011150] 'process raft request' (duration: 109.761721ms)","trace[628011150] 'compare' (duration: 95.731718ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:04.555612Z","caller":"traceutil/trace.go:171","msg":"trace[176011267] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"204.66446ms","start":"2026-03-03T19:58:04.350924Z","end":"2026-03-03T19:58:04.555588Z","steps":["trace[176011267] 'process raft request' (duration: 204.347191ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.555663Z","caller":"traceutil/trace.go:171","msg":"trace[1665357116] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"202.726525ms","start":"2026-03-03T19:58:04.352908Z","end":"2026-03-03T19:58:04.555634Z","steps":["trace[1665357116] 'process raft request' (duration: 202.64551ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.556129Z","caller":"traceutil/trace.go:171","msg":"trace[845268639] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"204.464678ms","start":"2026-03-03T19:58:04.351641Z","end":"2026-03-03T19:58:04.556105Z","steps":["trace[845268639] 'process raft request' (duration: 203.864032ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.572408Z","caller":"traceutil/trace.go:171","msg":"trace[1926797509] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"207.432735ms","start":"2026-03-03T19:58:04.364958Z","end":"2026-03-03T19:58:04.572391Z","steps":["trace[1926797509] 'process raft request' (duration: 207.323538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:04.868333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.513036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T19:58:04.868526Z","caller":"traceutil/trace.go:171","msg":"trace[2055589511] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"291.600365ms","start":"2026-03-03T19:58:04.576885Z","end":"2026-03-03T19:58:04.868486Z","steps":["trace[2055589511] 'process raft request' (duration: 147.856316ms)","trace[2055589511] 'compare' (duration: 143.297683ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:04.868596Z","caller":"traceutil/trace.go:171","msg":"trace[1294759190] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"277.408842ms","start":"2026-03-03T19:58:04.591166Z","end":"2026-03-03T19:58:04.868575Z","steps":["trace[1294759190] 'process raft request' (duration: 277.331088ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.868719Z","caller":"traceutil/trace.go:171","msg":"trace[736211630] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"290.549003ms","start":"2026-03-03T19:58:04.578123Z","end":"2026-03-03T19:58:04.868672Z","steps":["trace[736211630] 'process raft request' (duration: 290.32409ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.868787Z","caller":"traceutil/trace.go:171","msg":"trace[1799622449] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:349; }","duration":"260.462895ms","start":"2026-03-03T19:58:04.608316Z","end":"2026-03-03T19:58:04.868779Z","steps":["trace[1799622449] 'read index received' (duration: 116.435869ms)","trace[1799622449] 'applied index is now lower than readState.Index' (duration: 144.026176ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:04.868838Z","caller":"traceutil/trace.go:171","msg":"trace[1304946305] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"277.514248ms","start":"2026-03-03T19:58:04.591262Z","end":"2026-03-03T19:58:04.868776Z","steps":["trace[1304946305] 'process raft request' (duration: 277.274934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:04.868942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.468737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-03-03T19:58:04.868993Z","caller":"traceutil/trace.go:171","msg":"trace[365381058] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:341; }","duration":"258.53087ms","start":"2026-03-03T19:58:04.61045Z","end":"2026-03-03T19:58:04.868981Z","steps":["trace[365381058] 'agreement among raft nodes before linearized reading' (duration: 258.438005ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.869004Z","caller":"traceutil/trace.go:171","msg":"trace[1650884678] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"257.903432ms","start":"2026-03-03T19:58:04.611079Z","end":"2026-03-03T19:58:04.868982Z","steps":["trace[1650884678] 'process raft request' (duration: 257.640417ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:04.868882Z","caller":"traceutil/trace.go:171","msg":"trace[2010903763] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"257.594845ms","start":"2026-03-03T19:58:04.611256Z","end":"2026-03-03T19:58:04.868851Z","steps":["trace[2010903763] 'process raft request' (duration: 257.555622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:04.8689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.220712ms","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-03T19:58:04.869201Z","caller":"traceutil/trace.go:171","msg":"trace[851207283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:341; }","duration":"292.567753ms","start":"2026-03-03T19:58:04.576625Z","end":"2026-03-03T19:58:04.869192Z","steps":["trace[851207283] 'agreement among raft nodes before linearized reading' (duration: 292.222202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:05.226297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.874654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T19:58:05.226635Z","caller":"traceutil/trace.go:171","msg":"trace[1800650381] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:355; }","duration":"249.322173ms","start":"2026-03-03T19:58:04.977296Z","end":"2026-03-03T19:58:05.226618Z","steps":["trace[1800650381] 'read index received' (duration: 31.119219ms)","trace[1800650381] 'applied index is now lower than readState.Index' (duration: 218.200953ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:05.226761Z","caller":"traceutil/trace.go:171","msg":"trace[107850503] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"349.883009ms","start":"2026-03-03T19:58:04.876868Z","end":"2026-03-03T19:58:05.226751Z","steps":["trace[107850503] 'process raft request' (duration: 349.640774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:05.226831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:04.876857Z","time spent":"349.938332ms","remote":"127.0.0.1:33346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T19:58:05.22683Z","caller":"traceutil/trace.go:171","msg":"trace[1119839191] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"347.328277ms","start":"2026-03-03T19:58:04.87944Z","end":"2026-03-03T19:58:05.226768Z","steps":["trace[1119839191] 'process raft request' (duration: 347.139755ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:05.22698Z","caller":"traceutil/trace.go:171","msg":"trace[207623548] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"353.138472ms","start":"2026-03-03T19:58:04.873828Z","end":"2026-03-03T19:58:05.226966Z","steps":["trace[207623548] 'process raft request' (duration: 134.506703ms)","trace[207623548] 'compare' (duration: 217.740386ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:05.227104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:04.873817Z","time spent":"353.209716ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T19:58:05.227169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:04.879365Z","time spent":"347.691789ms","remote":"127.0.0.1:33058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T19:58:05.227194Z","caller":"traceutil/trace.go:171","msg":"trace[2009446233] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"349.849127ms","start":"2026-03-03T19:58:04.877298Z","end":"2026-03-03T19:58:05.227147Z","steps":["trace[2009446233] 'process raft request' (duration: 349.253942ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:05.227339Z","caller":"traceutil/trace.go:171","msg":"trace[1126020826] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"353.204326ms","start":"2026-03-03T19:58:04.874126Z","end":"2026-03-03T19:58:05.22733Z","steps":["trace[1126020826] 'process raft request' (duration: 352.342275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:05.227214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.921579ms","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-03T19:58:05.227355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:04.877276Z","time spent":"350.015247ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T19:58:05.227392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:04.87411Z","time spent":"353.255859ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T19:58:05.226924Z","caller":"traceutil/trace.go:171","msg":"trace[145396770] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"352.844595ms","start":"2026-03-03T19:58:04.874058Z","end":"2026-03-03T19:58:05.226902Z","steps":["trace[145396770] 'process raft request' (duration: 352.349495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:05.227468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:04.874044Z","time spent":"353.393027ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T19:58:05.227807Z","caller":"traceutil/trace.go:171","msg":"trace[580314927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:347; }","duration":"250.124251ms","start":"2026-03-03T19:58:04.97725Z","end":"2026-03-03T19:58:05.227375Z","steps":["trace[580314927] 'agreement among raft nodes before linearized reading' (duration: 249.934899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:05.55347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.388718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T19:58:05.553804Z","caller":"traceutil/trace.go:171","msg":"trace[1398912973] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"319.415826ms","start":"2026-03-03T19:58:05.234349Z","end":"2026-03-03T19:58:05.553765Z","steps":["trace[1398912973] 'process raft request' (duration: 319.251566ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:05.553819Z","caller":"traceutil/trace.go:171","msg":"trace[491267789] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"319.805959ms","start":"2026-03-03T19:58:05.233936Z","end":"2026-03-03T19:58:05.553742Z","steps":["trace[491267789] 'process raft request' (duration: 134.045379ms)","trace[491267789] 'compare' (duration: 185.228988ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:05.55387Z","caller":"traceutil/trace.go:171","msg":"trace[1640626790] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"314.206955ms","start":"2026-03-03T19:58:05.239627Z","end":"2026-03-03T19:58:05.553833Z","steps":["trace[1640626790] 'process raft request' (duration: 314.042405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:05.553992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:05.234313Z","time spent":"319.578496ms","remote":"127.0.0.1:33346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T19:58:05.554045Z","caller":"traceutil/trace.go:171","msg":"trace[1366361797] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:361; }","duration":"203.199909ms","start":"2026-03-03T19:58:05.350516Z","end":"2026-03-03T19:58:05.553715Z","steps":["trace[1366361797] 'read index received' (duration: 17.431399ms)","trace[1366361797] 'applied index is now lower than readState.Index' (duration: 185.76759ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:05.554129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:05.233924Z","time spent":"320.027832ms","remote":"127.0.0.1:33128","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-03T19:58:05.554177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:05.239615Z","time spent":"314.400037ms","remote":"127.0.0.1:33058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T19:58:05.554171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.582732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:58:05.554306Z","caller":"traceutil/trace.go:171","msg":"trace[1031156774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:350; }","duration":"203.830947ms","start":"2026-03-03T19:58:05.350453Z","end":"2026-03-03T19:58:05.554284Z","steps":["trace[1031156774] 'agreement among raft nodes before linearized reading' (duration: 203.522109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:05.853941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.663084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7293"} {"level":"info","ts":"2026-03-03T19:58:05.854061Z","caller":"traceutil/trace.go:171","msg":"trace[471893096] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:351; }","duration":"161.863366ms","start":"2026-03-03T19:58:05.692171Z","end":"2026-03-03T19:58:05.854035Z","steps":["trace[471893096] 'range keys from in-memory index tree' (duration: 161.507095ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:05.992835Z","caller":"traceutil/trace.go:171","msg":"trace[95502953] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"127.944325ms","start":"2026-03-03T19:58:05.86486Z","end":"2026-03-03T19:58:05.992804Z","steps":["trace[95502953] 'process raft request' (duration: 127.771885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:07.375329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360655940354868,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T19:58:07.544606Z","caller":"traceutil/trace.go:171","msg":"trace[1342270192] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"670.066848ms","start":"2026-03-03T19:58:06.874516Z","end":"2026-03-03T19:58:07.544583Z","steps":["trace[1342270192] 'read index received' (duration: 669.953071ms)","trace[1342270192] 'applied index is now lower than readState.Index' (duration: 112.967µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:07.544624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:06.64319Z","time spent":"901.429366ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-03T19:58:07.544746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.220877ms","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-03T19:58:07.544769Z","caller":"traceutil/trace.go:171","msg":"trace[763867315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"670.310263ms","start":"2026-03-03T19:58:06.874454Z","end":"2026-03-03T19:58:07.544764Z","steps":["trace[763867315] 'agreement among raft nodes before linearized reading' (duration: 670.238028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:07.54479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:06.87443Z","time spent":"670.355875ms","remote":"127.0.0.1:33128","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-03T19:58:07.544862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.256902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:58:07.544924Z","caller":"traceutil/trace.go:171","msg":"trace[1692239419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:353; }","duration":"195.362419ms","start":"2026-03-03T19:58:07.349544Z","end":"2026-03-03T19:58:07.544907Z","steps":["trace[1692239419] 'agreement among raft nodes before linearized reading' (duration: 195.277094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:07.544837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.531061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-03T19:58:07.544942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.458667ms","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-03T19:58:07.544994Z","caller":"traceutil/trace.go:171","msg":"trace[229822120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:353; }","duration":"312.772166ms","start":"2026-03-03T19:58:07.232215Z","end":"2026-03-03T19:58:07.544987Z","steps":["trace[229822120] 'agreement among raft nodes before linearized reading' (duration: 312.51262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:07.545014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:07.232195Z","time spent":"312.812458ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T19:58:07.545022Z","caller":"traceutil/trace.go:171","msg":"trace[1935085337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:353; }","duration":"312.562733ms","start":"2026-03-03T19:58:07.232442Z","end":"2026-03-03T19:58:07.545004Z","steps":["trace[1935085337] 'agreement among raft nodes before linearized reading' (duration: 312.446276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:07.545083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:07.232435Z","time spent":"312.630858ms","remote":"127.0.0.1:33128","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-03T19:58:08.058561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360655940354873,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T19:58:08.625044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.072987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T19:58:08.625151Z","caller":"traceutil/trace.go:171","msg":"trace[1999910976] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"1.078002981s","start":"2026-03-03T19:58:07.54713Z","end":"2026-03-03T19:58:08.625133Z","steps":["trace[1999910976] 'process raft request' (duration: 735.742492ms)","trace[1999910976] 'compare' (duration: 341.845844ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:08.625212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:07.5471Z","time spent":"1.078084605s","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T19:58:09.027569Z","caller":"traceutil/trace.go:171","msg":"trace[645785515] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"1.475573496s","start":"2026-03-03T19:58:07.551969Z","end":"2026-03-03T19:58:09.027543Z","steps":["trace[645785515] 'process raft request' (duration: 1.475517353s)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:09.027631Z","caller":"traceutil/trace.go:171","msg":"trace[273419015] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"1.478181873s","start":"2026-03-03T19:58:07.549415Z","end":"2026-03-03T19:58:09.027597Z","steps":["trace[273419015] 'process raft request' (duration: 1.477903427s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:09.027779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:07.549395Z","time spent":"1.478311381s","remote":"127.0.0.1:33128","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-03T19:58:09.027912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:07.551957Z","time spent":"1.475693854s","remote":"127.0.0.1:33128","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-03T19:58:09.028125Z","caller":"traceutil/trace.go:171","msg":"trace[1883594078] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:368; }","duration":"1.469910587s","start":"2026-03-03T19:58:07.558209Z","end":"2026-03-03T19:58:09.028119Z","steps":["trace[1883594078] 'read index received' (duration: 724.679438ms)","trace[1883594078] 'applied index is now lower than readState.Index' (duration: 745.230579ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:09.028247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.056212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:58:09.028277Z","caller":"traceutil/trace.go:171","msg":"trace[697893586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:357; }","duration":"678.127886ms","start":"2026-03-03T19:58:08.350141Z","end":"2026-03-03T19:58:09.028269Z","steps":["trace[697893586] 'agreement among raft nodes before linearized reading' (duration: 678.085783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:09.028293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470081797s","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-03T19:58:09.028318Z","caller":"traceutil/trace.go:171","msg":"trace[2103694940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:357; }","duration":"1.47012325s","start":"2026-03-03T19:58:07.558189Z","end":"2026-03-03T19:58:09.028312Z","steps":["trace[2103694940] 'agreement among raft nodes before linearized reading' (duration: 1.470077107s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:09.028335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:07.558181Z","time spent":"1.470151242s","remote":"127.0.0.1:33128","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-03T19:58:09.028356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:08.350117Z","time spent":"678.205991ms","remote":"127.0.0.1:32894","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-03T19:58:09.028396Z","caller":"traceutil/trace.go:171","msg":"trace[1290883273] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"1.476085227s","start":"2026-03-03T19:58:07.552306Z","end":"2026-03-03T19:58:09.028391Z","steps":["trace[1290883273] 'process raft request' (duration: 1.475213495s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:09.028423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:07.552288Z","time spent":"1.476118039s","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T19:58:09.02855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.682753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-03T19:58:09.028568Z","caller":"traceutil/trace.go:171","msg":"trace[1367184964] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:357; }","duration":"400.733976ms","start":"2026-03-03T19:58:08.62783Z","end":"2026-03-03T19:58:09.028564Z","steps":["trace[1367184964] 'agreement among raft nodes before linearized reading' (duration: 400.701904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:09.028582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:08.627812Z","time spent":"400.765568ms","remote":"127.0.0.1:33050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-03-03T19:58:09.232687Z","caller":"traceutil/trace.go:171","msg":"trace[1186665433] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"188.839277ms","start":"2026-03-03T19:58:09.043813Z","end":"2026-03-03T19:58:09.232653Z","steps":["trace[1186665433] 'process raft request' (duration: 142.552659ms)","trace[1186665433] 'compare' (duration: 46.161071ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:09.645937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.663256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-03-03T19:58:09.646045Z","caller":"traceutil/trace.go:171","msg":"trace[569448263] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:359; }","duration":"142.931792ms","start":"2026-03-03T19:58:09.503088Z","end":"2026-03-03T19:58:09.64602Z","steps":["trace[569448263] 'range keys from in-memory index tree' (duration: 142.527738ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:09.859525Z","caller":"traceutil/trace.go:171","msg":"trace[486604250] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"119.387923ms","start":"2026-03-03T19:58:09.740105Z","end":"2026-03-03T19:58:09.859493Z","steps":["trace[486604250] 'process raft request' (duration: 59.649704ms)","trace[486604250] 'compare' (duration: 59.613132ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:10.163072Z","caller":"traceutil/trace.go:171","msg":"trace[1310323364] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:376; }","duration":"197.577647ms","start":"2026-03-03T19:58:09.965452Z","end":"2026-03-03T19:58:10.163029Z","steps":["trace[1310323364] 'read index received' (duration: 122.148481ms)","trace[1310323364] 'applied index is now lower than readState.Index' (duration: 75.427446ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:10.163075Z","caller":"traceutil/trace.go:171","msg":"trace[1944050443] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"402.953212ms","start":"2026-03-03T19:58:09.760063Z","end":"2026-03-03T19:58:10.163016Z","steps":["trace[1944050443] 'process raft request' (duration: 327.688045ms)","trace[1944050443] 'compare' (duration: 74.897554ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:10.163277Z","caller":"traceutil/trace.go:171","msg":"trace[230793601] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"376.973617ms","start":"2026-03-03T19:58:09.786279Z","end":"2026-03-03T19:58:10.163253Z","steps":["trace[230793601] 'process raft request' (duration: 376.652848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:10.163416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:09.760041Z","time spent":"403.264201ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T19:58:10.16346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:09.786257Z","time spent":"377.073863ms","remote":"127.0.0.1:33128","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-03T19:58:10.164198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.740038ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:58:10.164319Z","caller":"traceutil/trace.go:171","msg":"trace[622097908] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:362; }","duration":"198.862005ms","start":"2026-03-03T19:58:09.965439Z","end":"2026-03-03T19:58:10.164301Z","steps":["trace[622097908] 'agreement among raft nodes before linearized reading' (duration: 197.744848ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:10.314342Z","caller":"traceutil/trace.go:171","msg":"trace[540808057] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"142.309286ms","start":"2026-03-03T19:58:10.171999Z","end":"2026-03-03T19:58:10.314308Z","steps":["trace[540808057] 'process raft request' (duration: 115.816816ms)","trace[540808057] 'compare' (duration: 26.376883ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:10.855483Z","caller":"traceutil/trace.go:171","msg":"trace[674476363] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"361.370467ms","start":"2026-03-03T19:58:10.494088Z","end":"2026-03-03T19:58:10.855459Z","steps":["trace[674476363] 'process raft request' (duration: 361.248159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:10.855627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:10.494066Z","time spent":"361.489423ms","remote":"127.0.0.1:33058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T19:58:11.138703Z","caller":"traceutil/trace.go:171","msg":"trace[1348139895] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"106.000158ms","start":"2026-03-03T19:58:11.032644Z","end":"2026-03-03T19:58:11.138644Z","steps":["trace[1348139895] 'read index received' (duration: 26.859475ms)","trace[1348139895] 'applied index is now lower than readState.Index' (duration: 79.139453ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:11.138754Z","caller":"traceutil/trace.go:171","msg":"trace[1475762499] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"275.223023ms","start":"2026-03-03T19:58:10.863498Z","end":"2026-03-03T19:58:11.138721Z","steps":["trace[1475762499] 'process raft request' (duration: 196.091802ms)","trace[1475762499] 'compare' (duration: 78.836214ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:11.138893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.675098ms","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-03T19:58:11.138946Z","caller":"traceutil/trace.go:171","msg":"trace[553573503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:365; }","duration":"105.779265ms","start":"2026-03-03T19:58:11.033149Z","end":"2026-03-03T19:58:11.138929Z","steps":["trace[553573503] 'agreement among raft nodes before linearized reading' (duration: 105.679519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:11.139108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.686599ms","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-03T19:58:11.139242Z","caller":"traceutil/trace.go:171","msg":"trace[2071589844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:365; }","duration":"105.866241ms","start":"2026-03-03T19:58:11.033336Z","end":"2026-03-03T19:58:11.139203Z","steps":["trace[2071589844] 'agreement among raft nodes before linearized reading' (duration: 105.539201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:11.139182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.52451ms","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-03T19:58:11.139432Z","caller":"traceutil/trace.go:171","msg":"trace[274793707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:365; }","duration":"106.829708ms","start":"2026-03-03T19:58:11.032582Z","end":"2026-03-03T19:58:11.139412Z","steps":["trace[274793707] 'agreement among raft nodes before linearized reading' (duration: 106.300776ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:11.31517Z","caller":"traceutil/trace.go:171","msg":"trace[1568547469] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"171.644393ms","start":"2026-03-03T19:58:11.143513Z","end":"2026-03-03T19:58:11.315157Z","steps":["trace[1568547469] 'process raft request' (duration: 171.553587ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:11.545309Z","caller":"traceutil/trace.go:171","msg":"trace[1642054164] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"401.743135ms","start":"2026-03-03T19:58:11.143547Z","end":"2026-03-03T19:58:11.54529Z","steps":["trace[1642054164] 'process raft request' (duration: 308.545972ms)","trace[1642054164] 'compare' (duration: 93.053334ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:11.545473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:11.14353Z","time spent":"401.851051ms","remote":"127.0.0.1:33128","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-03T19:58:11.545503Z","caller":"traceutil/trace.go:171","msg":"trace[245742189] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:381; }","duration":"309.471678ms","start":"2026-03-03T19:58:11.236009Z","end":"2026-03-03T19:58:11.545481Z","steps":["trace[245742189] 'read index received' (duration: 79.10095ms)","trace[245742189] 'applied index is now lower than readState.Index' (duration: 230.369828ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:11.545615Z","caller":"traceutil/trace.go:171","msg":"trace[1398327868] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"401.50138ms","start":"2026-03-03T19:58:11.144094Z","end":"2026-03-03T19:58:11.545596Z","steps":["trace[1398327868] 'process raft request' (duration: 401.16133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:11.545707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:11.144083Z","time spent":"401.573705ms","remote":"127.0.0.1:33128","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-03T19:58:11.545695Z","caller":"traceutil/trace.go:171","msg":"trace[1424093074] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"396.998798ms","start":"2026-03-03T19:58:11.148685Z","end":"2026-03-03T19:58:11.545683Z","steps":["trace[1424093074] 'process raft request' (duration: 396.695929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:11.545795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.995728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T19:58:11.545884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.880343ms","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-03T19:58:11.545898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:11.148676Z","time spent":"397.141547ms","remote":"127.0.0.1:33058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T19:58:11.545941Z","caller":"traceutil/trace.go:171","msg":"trace[279173960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"309.978389ms","start":"2026-03-03T19:58:11.235947Z","end":"2026-03-03T19:58:11.545925Z","steps":["trace[279173960] 'agreement among raft nodes before linearized reading' (duration: 309.889444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:11.545988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:11.235924Z","time spent":"310.053653ms","remote":"127.0.0.1:33128","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-03T19:58:11.545843Z","caller":"traceutil/trace.go:171","msg":"trace[1778891464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"196.082262ms","start":"2026-03-03T19:58:11.349746Z","end":"2026-03-03T19:58:11.545829Z","steps":["trace[1778891464] 'agreement among raft nodes before linearized reading' (duration: 195.995697ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:11.826606Z","caller":"traceutil/trace.go:171","msg":"trace[1025091824] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"258.721534ms","start":"2026-03-03T19:58:11.567848Z","end":"2026-03-03T19:58:11.826569Z","steps":["trace[1025091824] 'process raft request' (duration: 169.647471ms)","trace[1025091824] 'compare' (duration: 88.933685ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:12.068337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.16817ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T19:58:13.431753Z","caller":"traceutil/trace.go:171","msg":"trace[2107483718] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"108.704652ms","start":"2026-03-03T19:58:13.323023Z","end":"2026-03-03T19:58:13.431728Z","steps":["trace[2107483718] 'process raft request' (duration: 108.149128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:13.672637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.307219ms","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-03T19:58:13.672755Z","caller":"traceutil/trace.go:171","msg":"trace[776142371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:373; }","duration":"121.411756ms","start":"2026-03-03T19:58:13.551298Z","end":"2026-03-03T19:58:13.67271Z","steps":["trace[776142371] 'range keys from in-memory index tree' (duration: 121.214923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:13.672636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.595247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T19:58:13.672836Z","caller":"traceutil/trace.go:171","msg":"trace[1366191624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"121.869693ms","start":"2026-03-03T19:58:13.55095Z","end":"2026-03-03T19:58:13.67282Z","steps":["trace[1366191624] 'range keys from in-memory index tree' (duration: 121.467738ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:13.672825Z","caller":"traceutil/trace.go:171","msg":"trace[1641921827] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"110.827791ms","start":"2026-03-03T19:58:13.561974Z","end":"2026-03-03T19:58:13.672802Z","steps":["trace[1641921827] 'read index received' (duration: 67.90689ms)","trace[1641921827] 'applied index is now lower than readState.Index' (duration: 42.916531ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:13.672903Z","caller":"traceutil/trace.go:171","msg":"trace[1644109863] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"120.349351ms","start":"2026-03-03T19:58:13.552522Z","end":"2026-03-03T19:58:13.672872Z","steps":["trace[1644109863] 'process raft request' (duration: 77.444481ms)","trace[1644109863] 'compare' (duration: 42.550698ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:13.672941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.953529ms","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-03T19:58:13.672998Z","caller":"traceutil/trace.go:171","msg":"trace[1208023313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:374; }","duration":"111.027033ms","start":"2026-03-03T19:58:13.561958Z","end":"2026-03-03T19:58:13.672985Z","steps":["trace[1208023313] 'agreement among raft nodes before linearized reading' (duration: 110.905906ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:15.971336Z","caller":"traceutil/trace.go:171","msg":"trace[378814416] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"245.53796ms","start":"2026-03-03T19:58:15.725765Z","end":"2026-03-03T19:58:15.971303Z","steps":["trace[378814416] 'process raft request' (duration: 238.476099ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:15.975539Z","caller":"traceutil/trace.go:171","msg":"trace[289823757] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"247.171369ms","start":"2026-03-03T19:58:15.728339Z","end":"2026-03-03T19:58:15.975511Z","steps":["trace[289823757] 'process raft request' (duration: 247.01779ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:16.781566Z","caller":"traceutil/trace.go:171","msg":"trace[387376825] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"106.477229ms","start":"2026-03-03T19:58:16.675055Z","end":"2026-03-03T19:58:16.781532Z","steps":["trace[387376825] 'process raft request' (duration: 74.351201ms)","trace[387376825] 'compare' (duration: 31.945716ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:17.921262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.773968ms","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-03T19:58:17.921678Z","caller":"traceutil/trace.go:171","msg":"trace[1294119197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:383; }","duration":"195.96634ms","start":"2026-03-03T19:58:17.725401Z","end":"2026-03-03T19:58:17.921367Z","steps":["trace[1294119197] 'range keys from in-memory index tree' (duration: 195.636719ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:18.051036Z","caller":"traceutil/trace.go:171","msg":"trace[926621529] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"125.113119ms","start":"2026-03-03T19:58:17.925887Z","end":"2026-03-03T19:58:18.051Z","steps":["trace[926621529] 'process raft request' (duration: 124.827622ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:18.256877Z","caller":"traceutil/trace.go:171","msg":"trace[638468501] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"200.371293ms","start":"2026-03-03T19:58:18.056475Z","end":"2026-03-03T19:58:18.256846Z","steps":["trace[638468501] 'process raft request' (duration: 194.372844ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:23.20172Z","caller":"traceutil/trace.go:171","msg":"trace[1159622221] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"108.058458ms","start":"2026-03-03T19:58:23.093636Z","end":"2026-03-03T19:58:23.201694Z","steps":["trace[1159622221] 'process raft request' (duration: 107.645052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:23.483625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.458543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T19:58:23.483827Z","caller":"traceutil/trace.go:171","msg":"trace[2104944979] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"275.508356ms","start":"2026-03-03T19:58:23.208295Z","end":"2026-03-03T19:58:23.483803Z","steps":["trace[2104944979] 'process raft request' (duration: 130.811208ms)","trace[2104944979] 'compare' (duration: 143.548877ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:23.486544Z","caller":"traceutil/trace.go:171","msg":"trace[132617090] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"138.994304ms","start":"2026-03-03T19:58:23.347522Z","end":"2026-03-03T19:58:23.486517Z","steps":["trace[132617090] 'read index received' (duration: 138.821644ms)","trace[132617090] 'applied index is now lower than readState.Index' (duration: 171.57µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:23.486753Z","caller":"traceutil/trace.go:171","msg":"trace[1071636351] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"244.526966ms","start":"2026-03-03T19:58:23.242174Z","end":"2026-03-03T19:58:23.486701Z","steps":["trace[1071636351] 'process raft request' (duration: 244.233298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:23.486996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.460483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:58:23.487045Z","caller":"traceutil/trace.go:171","msg":"trace[1494473372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"139.534478ms","start":"2026-03-03T19:58:23.347491Z","end":"2026-03-03T19:58:23.487026Z","steps":["trace[1494473372] 'agreement among raft nodes before linearized reading' (duration: 139.102851ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:23.636461Z","caller":"traceutil/trace.go:171","msg":"trace[1927429216] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"141.97546ms","start":"2026-03-03T19:58:23.494466Z","end":"2026-03-03T19:58:23.636441Z","steps":["trace[1927429216] 'process raft request' (duration: 141.921197ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:23.636483Z","caller":"traceutil/trace.go:171","msg":"trace[2128319115] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"142.515943ms","start":"2026-03-03T19:58:23.493931Z","end":"2026-03-03T19:58:23.636447Z","steps":["trace[2128319115] 'process raft request' (duration: 115.51809ms)","trace[2128319115] 'compare' (duration: 26.794011ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:23.636467Z","caller":"traceutil/trace.go:171","msg":"trace[467511653] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"142.330532ms","start":"2026-03-03T19:58:23.494109Z","end":"2026-03-03T19:58:23.63644Z","steps":["trace[467511653] 'process raft request' (duration: 142.243987ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:23.636742Z","caller":"traceutil/trace.go:171","msg":"trace[1654560167] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:421; }","duration":"116.662061ms","start":"2026-03-03T19:58:23.520071Z","end":"2026-03-03T19:58:23.636734Z","steps":["trace[1654560167] 'read index received' (duration: 89.502298ms)","trace[1654560167] 'applied index is now lower than readState.Index' (duration: 27.159133ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:23.636824Z","caller":"traceutil/trace.go:171","msg":"trace[912841279] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"142.153601ms","start":"2026-03-03T19:58:23.494662Z","end":"2026-03-03T19:58:23.636815Z","steps":["trace[912841279] 'process raft request' (duration: 141.746016ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:23.636923Z","caller":"traceutil/trace.go:171","msg":"trace[501227086] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"115.979488ms","start":"2026-03-03T19:58:23.520921Z","end":"2026-03-03T19:58:23.6369Z","steps":["trace[501227086] 'process raft request' (duration: 115.751494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:23.637038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.945498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-cp4xn\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-03-03T19:58:23.637077Z","caller":"traceutil/trace.go:171","msg":"trace[1188253300] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-cp4xn; range_end:; response_count:1; response_revision:407; }","duration":"117.022194ms","start":"2026-03-03T19:58:23.520041Z","end":"2026-03-03T19:58:23.637063Z","steps":["trace[1188253300] 'agreement among raft nodes before linearized reading' (duration: 116.754347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:23.638057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.758233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-03-03T19:58:23.638101Z","caller":"traceutil/trace.go:171","msg":"trace[266148520] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:407; }","duration":"114.806596ms","start":"2026-03-03T19:58:23.523283Z","end":"2026-03-03T19:58:23.638089Z","steps":["trace[266148520] 'agreement among raft nodes before linearized reading' (duration: 114.724051ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:23.836515Z","caller":"traceutil/trace.go:171","msg":"trace[780168275] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"139.823677ms","start":"2026-03-03T19:58:23.696664Z","end":"2026-03-03T19:58:23.836488Z","steps":["trace[780168275] 'process raft request' (duration: 139.752092ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:23.836551Z","caller":"traceutil/trace.go:171","msg":"trace[988492190] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"139.931454ms","start":"2026-03-03T19:58:23.696574Z","end":"2026-03-03T19:58:23.836506Z","steps":["trace[988492190] 'process raft request' (duration: 70.294968ms)","trace[988492190] 'compare' (duration: 69.398551ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:23.837076Z","caller":"traceutil/trace.go:171","msg":"trace[332391486] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:433; }","duration":"121.440307ms","start":"2026-03-03T19:58:23.71562Z","end":"2026-03-03T19:58:23.83706Z","steps":["trace[332391486] 'read index received' (duration: 51.414397ms)","trace[332391486] 'applied index is now lower than readState.Index' (duration: 70.02511ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:23.837195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.552094ms","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-03T19:58:23.837179Z","caller":"traceutil/trace.go:171","msg":"trace[548912613] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"135.532201ms","start":"2026-03-03T19:58:23.701624Z","end":"2026-03-03T19:58:23.837157Z","steps":["trace[548912613] 'process raft request' (duration: 135.34116ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:23.837231Z","caller":"traceutil/trace.go:171","msg":"trace[67113377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"121.626829ms","start":"2026-03-03T19:58:23.715591Z","end":"2026-03-03T19:58:23.837218Z","steps":["trace[67113377] 'agreement among raft nodes before linearized reading' (duration: 121.530763ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:24.071376Z","caller":"traceutil/trace.go:171","msg":"trace[1515933600] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"130.530648ms","start":"2026-03-03T19:58:23.940812Z","end":"2026-03-03T19:58:24.071343Z","steps":["trace[1515933600] 'process raft request' (duration: 130.300573ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:24.071764Z","caller":"traceutil/trace.go:171","msg":"trace[2127702013] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"130.610062ms","start":"2026-03-03T19:58:23.940961Z","end":"2026-03-03T19:58:24.071571Z","steps":["trace[2127702013] 'process raft request' (duration: 130.319894ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:24.072019Z","caller":"traceutil/trace.go:171","msg":"trace[861904031] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"126.901092ms","start":"2026-03-03T19:58:23.945098Z","end":"2026-03-03T19:58:24.071999Z","steps":["trace[861904031] 'process raft request' (duration: 126.448204ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:24.072561Z","caller":"traceutil/trace.go:171","msg":"trace[1955191283] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"125.392229ms","start":"2026-03-03T19:58:23.947152Z","end":"2026-03-03T19:58:24.072544Z","steps":["trace[1955191283] 'process raft request' (duration: 125.277382ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:24.072713Z","caller":"traceutil/trace.go:171","msg":"trace[233351109] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"124.678515ms","start":"2026-03-03T19:58:23.948014Z","end":"2026-03-03T19:58:24.072693Z","steps":["trace[233351109] 'process raft request' (duration: 124.482722ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:24.279936Z","caller":"traceutil/trace.go:171","msg":"trace[1359852109] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"187.584765ms","start":"2026-03-03T19:58:24.09232Z","end":"2026-03-03T19:58:24.279904Z","steps":["trace[1359852109] 'read index received' (duration: 103.866128ms)","trace[1359852109] 'applied index is now lower than readState.Index' (duration: 83.717557ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:24.280091Z","caller":"traceutil/trace.go:171","msg":"trace[1838592789] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"200.604633ms","start":"2026-03-03T19:58:24.07944Z","end":"2026-03-03T19:58:24.280045Z","steps":["trace[1838592789] 'process raft request' (duration: 116.719916ms)","trace[1838592789] 'compare' (duration: 83.620121ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:24.280169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.81817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-03-03T19:58:24.280464Z","caller":"traceutil/trace.go:171","msg":"trace[1253899980] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:427; }","duration":"188.176332ms","start":"2026-03-03T19:58:24.092271Z","end":"2026-03-03T19:58:24.280447Z","steps":["trace[1253899980] 'agreement among raft nodes before linearized reading' (duration: 187.774767ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:24.312119Z","caller":"traceutil/trace.go:171","msg":"trace[450031913] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"218.160113ms","start":"2026-03-03T19:58:24.093926Z","end":"2026-03-03T19:58:24.312086Z","steps":["trace[450031913] 'process raft request' (duration: 217.695914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:24.312449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.387008ms","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-03T19:58:24.312505Z","caller":"traceutil/trace.go:171","msg":"trace[1692757382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:428; }","duration":"127.449722ms","start":"2026-03-03T19:58:24.185042Z","end":"2026-03-03T19:58:24.312492Z","steps":["trace[1692757382] 'agreement among raft nodes before linearized reading' (duration: 127.347536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:24.312698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.099742ms","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-03T19:58:24.312742Z","caller":"traceutil/trace.go:171","msg":"trace[1197882445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:428; }","duration":"195.172706ms","start":"2026-03-03T19:58:24.117561Z","end":"2026-03-03T19:58:24.312734Z","steps":["trace[1197882445] 'agreement among raft nodes before linearized reading' (duration: 195.099112ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:24.509875Z","caller":"traceutil/trace.go:171","msg":"trace[1947637963] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"154.451539ms","start":"2026-03-03T19:58:24.355411Z","end":"2026-03-03T19:58:24.509863Z","steps":["trace[1947637963] 'process raft request' (duration: 74.766071ms)","trace[1947637963] 'compare' (duration: 79.479385ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:24.51024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.753573ms","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-03T19:58:24.51027Z","caller":"traceutil/trace.go:171","msg":"trace[263264879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"147.845869ms","start":"2026-03-03T19:58:24.362418Z","end":"2026-03-03T19:58:24.510264Z","steps":["trace[263264879] 'agreement among raft nodes before linearized reading' (duration: 147.770054ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:24.509795Z","caller":"traceutil/trace.go:171","msg":"trace[1506418473] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"147.296245ms","start":"2026-03-03T19:58:24.362472Z","end":"2026-03-03T19:58:24.509768Z","steps":["trace[1506418473] 'read index received' (duration: 67.735575ms)","trace[1506418473] 'applied index is now lower than readState.Index' (duration: 79.55947ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:27.200428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.99498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-03T19:58:27.200559Z","caller":"traceutil/trace.go:171","msg":"trace[495413450] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:436; }","duration":"558.195133ms","start":"2026-03-03T19:58:26.642331Z","end":"2026-03-03T19:58:27.200526Z","steps":["trace[495413450] 'range keys from in-memory index tree' (duration: 557.818299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:27.200613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:26.642309Z","time spent":"558.294019ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"info","ts":"2026-03-03T19:58:28.569406Z","caller":"traceutil/trace.go:171","msg":"trace[1802170012] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"150.149045ms","start":"2026-03-03T19:58:28.419234Z","end":"2026-03-03T19:58:28.569383Z","steps":["trace[1802170012] 'process raft request' (duration: 149.993235ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:28.798488Z","caller":"traceutil/trace.go:171","msg":"trace[196553614] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:458; }","duration":"245.888143ms","start":"2026-03-03T19:58:28.552573Z","end":"2026-03-03T19:58:28.798461Z","steps":["trace[196553614] 'read index received' (duration: 16.707934ms)","trace[196553614] 'applied index is now lower than readState.Index' (duration: 229.179359ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:28.798617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.032422ms","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-03T19:58:28.79863Z","caller":"traceutil/trace.go:171","msg":"trace[580500636] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"379.276632ms","start":"2026-03-03T19:58:28.419304Z","end":"2026-03-03T19:58:28.798581Z","steps":["trace[580500636] 'process raft request' (duration: 379.030727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:28.798837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:28.419282Z","time spent":"379.474294ms","remote":"127.0.0.1:33128","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-03T19:58:28.798657Z","caller":"traceutil/trace.go:171","msg":"trace[892174615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:440; }","duration":"246.138029ms","start":"2026-03-03T19:58:28.552509Z","end":"2026-03-03T19:58:28.798647Z","steps":["trace[892174615] 'agreement among raft nodes before linearized reading' (duration: 246.050544ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:34.143656Z","caller":"traceutil/trace.go:171","msg":"trace[808940735] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"221.078098ms","start":"2026-03-03T19:58:33.92254Z","end":"2026-03-03T19:58:34.143618Z","steps":["trace[808940735] 'process raft request' (duration: 220.391565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:34.911613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.179315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:58:34.911711Z","caller":"traceutil/trace.go:171","msg":"trace[315101570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:451; }","duration":"561.360333ms","start":"2026-03-03T19:58:34.350326Z","end":"2026-03-03T19:58:34.911686Z","steps":["trace[315101570] 'range keys from in-memory index tree' (duration: 560.993284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:34.91176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:34.350295Z","time spent":"561.455547ms","remote":"127.0.0.1:32894","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-03T19:58:34.912214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.744639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T19:58:34.912357Z","caller":"traceutil/trace.go:171","msg":"trace[790557178] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"322.469706ms","start":"2026-03-03T19:58:34.589865Z","end":"2026-03-03T19:58:34.912334Z","steps":["trace[790557178] 'read index received' (duration: 218.518011ms)","trace[790557178] 'applied index is now lower than readState.Index' (duration: 103.949945ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:34.912385Z","caller":"traceutil/trace.go:171","msg":"trace[1909302231] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"479.273598ms","start":"2026-03-03T19:58:34.433078Z","end":"2026-03-03T19:58:34.912351Z","steps":["trace[1909302231] 'process raft request' (duration: 375.25239ms)","trace[1909302231] 'compare' (duration: 103.404719ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:34.912541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.675892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-03T19:58:34.91257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:34.433055Z","time spent":"479.407452ms","remote":"127.0.0.1:33128","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-03T19:58:34.91261Z","caller":"traceutil/trace.go:171","msg":"trace[1057536017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:452; }","duration":"322.793248ms","start":"2026-03-03T19:58:34.589798Z","end":"2026-03-03T19:58:34.912591Z","steps":["trace[1057536017] 'agreement among raft nodes before linearized reading' (duration: 322.653524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:34.912656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:34.589767Z","time spent":"322.877482ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T19:58:38.387988Z","caller":"traceutil/trace.go:171","msg":"trace[1748015521] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"223.889035ms","start":"2026-03-03T19:58:38.164049Z","end":"2026-03-03T19:58:38.387938Z","steps":["trace[1748015521] 'process raft request' (duration: 223.693047ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T19:58:45.497076Z","caller":"traceutil/trace.go:171","msg":"trace[1444848273] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"634.102566ms","start":"2026-03-03T19:58:44.862949Z","end":"2026-03-03T19:58:45.497052Z","steps":["trace[1444848273] 'process raft request' (duration: 633.957423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:45.497427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:44.862926Z","time spent":"634.23652ms","remote":"127.0.0.1:33058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T19:58:45.511221Z","caller":"traceutil/trace.go:171","msg":"trace[531501124] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:558; }","duration":"418.466793ms","start":"2026-03-03T19:58:45.092734Z","end":"2026-03-03T19:58:45.511201Z","steps":["trace[531501124] 'read index received' (duration: 404.268929ms)","trace[531501124] 'applied index is now lower than readState.Index' (duration: 14.196974ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:45.511491Z","caller":"traceutil/trace.go:171","msg":"trace[1435211501] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"491.966508ms","start":"2026-03-03T19:58:45.019515Z","end":"2026-03-03T19:58:45.511481Z","steps":["trace[1435211501] 'process raft request' (duration: 491.580221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:45.511582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:45.019483Z","time spent":"492.032355ms","remote":"127.0.0.1:33128","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-03T19:58:45.511705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.952231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T19:58:45.511766Z","caller":"traceutil/trace.go:171","msg":"trace[1366667447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"419.068309ms","start":"2026-03-03T19:58:45.092683Z","end":"2026-03-03T19:58:45.511752Z","steps":["trace[1366667447] 'agreement among raft nodes before linearized reading' (duration: 418.854639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:45.511803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:45.092663Z","time spent":"419.134056ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T19:58:45.512093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.842462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:58:45.512121Z","caller":"traceutil/trace.go:171","msg":"trace[383096925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"161.870616ms","start":"2026-03-03T19:58:45.350244Z","end":"2026-03-03T19:58:45.512115Z","steps":["trace[383096925] 'agreement among raft nodes before linearized reading' (duration: 161.828614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:45.512248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.09636ms","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-03T19:58:45.51233Z","caller":"traceutil/trace.go:171","msg":"trace[823765330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"414.221697ms","start":"2026-03-03T19:58:45.098092Z","end":"2026-03-03T19:58:45.512313Z","steps":["trace[823765330] 'agreement among raft nodes before linearized reading' (duration: 413.978653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:45.512409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:45.098077Z","time spent":"414.294683ms","remote":"127.0.0.1:33128","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-03T19:58:45.512469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.09632ms","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-03T19:58:45.512593Z","caller":"traceutil/trace.go:171","msg":"trace[982083478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"414.2567ms","start":"2026-03-03T19:58:45.09831Z","end":"2026-03-03T19:58:45.512566Z","steps":["trace[982083478] 'agreement among raft nodes before linearized reading' (duration: 413.837679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:45.512645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:45.098303Z","time spent":"414.332586ms","remote":"127.0.0.1:33128","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":"info","ts":"2026-03-03T19:58:46.147685Z","caller":"traceutil/trace.go:171","msg":"trace[67782943] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"633.407012ms","start":"2026-03-03T19:58:45.514252Z","end":"2026-03-03T19:58:46.147659Z","steps":["trace[67782943] 'process raft request' (duration: 547.113689ms)","trace[67782943] 'compare' (duration: 86.003737ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:46.147809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:45.514229Z","time spent":"633.515773ms","remote":"127.0.0.1:33128","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-03T19:58:46.170484Z","caller":"traceutil/trace.go:171","msg":"trace[1077037290] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"654.535195ms","start":"2026-03-03T19:58:45.515922Z","end":"2026-03-03T19:58:46.170457Z","steps":["trace[1077037290] 'process raft request' (duration: 654.284131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:46.170717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:45.515906Z","time spent":"654.650383ms","remote":"127.0.0.1:33128","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-03T19:58:46.170723Z","caller":"traceutil/trace.go:171","msg":"trace[1330514182] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"654.442843ms","start":"2026-03-03T19:58:45.516262Z","end":"2026-03-03T19:58:46.170705Z","steps":["trace[1330514182] 'process raft request' (duration: 654.145978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:46.170921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:45.516249Z","time spent":"654.592345ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T19:58:47.144857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360655940355271,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T19:58:47.591034Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.100304513s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T19:58:47.591396Z","caller":"traceutil/trace.go:171","msg":"trace[732029911] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:563; }","duration":"947.223784ms","start":"2026-03-03T19:58:46.644149Z","end":"2026-03-03T19:58:47.591373Z","steps":["trace[732029911] 'read index received' (duration: 947.009423ms)","trace[732029911] 'applied index is now lower than readState.Index' (duration: 213.302µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T19:58:47.591504Z","caller":"traceutil/trace.go:171","msg":"trace[1159372198] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.100855172s","start":"2026-03-03T19:58:46.490628Z","end":"2026-03-03T19:58:47.591483Z","steps":["trace[1159372198] 'process raft request' (duration: 1.100606598s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:47.591563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.402652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-03T19:58:47.591603Z","caller":"traceutil/trace.go:171","msg":"trace[483354727] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:541; }","duration":"947.500573ms","start":"2026-03-03T19:58:46.644091Z","end":"2026-03-03T19:58:47.591592Z","steps":["trace[483354727] 'agreement among raft nodes before linearized reading' (duration: 947.400212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:47.591616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:46.490607Z","time spent":"1.100931778s","remote":"127.0.0.1:33128","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-03T19:58:47.591636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:46.644067Z","time spent":"947.561923ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"warn","ts":"2026-03-03T19:58:47.5917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.559195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:58:47.591771Z","caller":"traceutil/trace.go:171","msg":"trace[1508889102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"244.667356ms","start":"2026-03-03T19:58:47.347085Z","end":"2026-03-03T19:58:47.591752Z","steps":["trace[1508889102] 'agreement among raft nodes before linearized reading' (duration: 244.532591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:48.659442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360655940355275,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T19:58:48.674951Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.081755759s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-03T19:58:48.845597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.531374ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-03T19:58:48.8457Z","caller":"traceutil/trace.go:171","msg":"trace[1394520147] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"686.775044ms","start":"2026-03-03T19:58:48.158904Z","end":"2026-03-03T19:58:48.845679Z","steps":["trace[1394520147] 'read index received' (duration: 516.145408ms)","trace[1394520147] 'applied index is now lower than readState.Index' (duration: 170.627636ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T19:58:48.845841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:47.593032Z","time spent":"1.25280643s","remote":"127.0.0.1:32910","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-03T19:58:48.846105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.024732ms","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-03T19:58:48.846182Z","caller":"traceutil/trace.go:171","msg":"trace[1130013018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"671.145611ms","start":"2026-03-03T19:58:48.175019Z","end":"2026-03-03T19:58:48.846165Z","steps":["trace[1130013018] 'agreement among raft nodes before linearized reading' (duration: 670.98662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:48.846229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:48.174999Z","time spent":"671.221507ms","remote":"127.0.0.1:33128","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-03T19:58:48.846234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.310281ms","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-03T19:58:48.846268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.360249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T19:58:48.846282Z","caller":"traceutil/trace.go:171","msg":"trace[191453352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"670.388956ms","start":"2026-03-03T19:58:48.17588Z","end":"2026-03-03T19:58:48.846269Z","steps":["trace[191453352] 'agreement among raft nodes before linearized reading' (duration: 670.294783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:48.846327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:48.175863Z","time spent":"670.455655ms","remote":"127.0.0.1:33128","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-03T19:58:48.846326Z","caller":"traceutil/trace.go:171","msg":"trace[1841076534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"687.454343ms","start":"2026-03-03T19:58:48.158859Z","end":"2026-03-03T19:58:48.846313Z","steps":["trace[1841076534] 'agreement among raft nodes before linearized reading' (duration: 687.349381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:48.846369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:48.15884Z","time spent":"687.522011ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T19:58:48.846937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.329225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T19:58:48.84702Z","caller":"traceutil/trace.go:171","msg":"trace[1355460103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"498.466641ms","start":"2026-03-03T19:58:48.348537Z","end":"2026-03-03T19:58:48.847004Z","steps":["trace[1355460103] 'agreement among raft nodes before linearized reading' (duration: 497.596817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T19:58:48.847056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T19:58:48.348518Z","time spent":"498.532369ms","remote":"127.0.0.1:32894","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-03T20:00:29.542598Z","caller":"traceutil/trace.go:171","msg":"trace[2016535328] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"122.483697ms","start":"2026-03-03T20:00:29.420078Z","end":"2026-03-03T20:00:29.542562Z","steps":["trace[2016535328] 'process raft request' (duration: 122.258049ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T20:00:30.265035Z","caller":"traceutil/trace.go:171","msg":"trace[635078937] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"133.129505ms","start":"2026-03-03T20:00:30.131876Z","end":"2026-03-03T20:00:30.265006Z","steps":["trace[635078937] 'process raft request' (duration: 132.938037ms)"],"step_count":1}