{"level":"warn","ts":"2026-03-07T09:58:36.223166Z","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-07T09:58:36.223578Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-07T09:58:36.22371Z","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-07T09:58:36.223728Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-03-07T09:58:36.22377Z","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-07T09:58:36.225238Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-03-07T09:58:36.225603Z","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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-07T09:58:36.716648Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"490.751497ms"} {"level":"info","ts":"2026-03-07T09:58:36.762469Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-03-07T09:58:36.76267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-03-07T09:58:36.762744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-03-07T09:58:36.762783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-07T09:58:36.762834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-03-07T09:58:36.762918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-03-07T09:58:36.791034Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-07T09:58:36.797104Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-07T09:58:36.888323Z","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-07T09:58:36.922212Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-07T09:58:36.922458Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-07T09:58:36.922632Z","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-07T09:58:36.922905Z","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-07T09:58:36.923105Z","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-07T09:58:36.925824Z","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-07T09:58:36.926149Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-03-07T09:58:36.926277Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-03-07T09:58:36.926445Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-07T09:58:36.926659Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-07T09:58:36.932096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-03-07T09:58:36.932349Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-03-07T09:58:37.766259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-03-07T09:58:37.766341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-07T09:58:37.766394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-03-07T09:58:37.766418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-03-07T09:58:37.766435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-03-07T09:58:37.766453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-03-07T09:58:37.766473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-03-07T09:58:38.018826Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T09:58:38.019059Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-03-07T09:58:38.019074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-07T09:58:38.019172Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-07T09:58:38.019543Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-07T09:58:38.019616Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-07T09:58:38.022053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T09:58:38.022344Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T09:58:38.022588Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T09:58:38.024185Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-03-07T09:58:38.025145Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-07T09:58:39.73852Z","caller":"traceutil/trace.go:171","msg":"trace[1151820133] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"393.686039ms","start":"2026-03-07T09:58:39.344805Z","end":"2026-03-07T09:58:39.738491Z","steps":["trace[1151820133] 'process raft request' (duration: 307.181436ms)","trace[1151820133] 'compare' (duration: 86.306292ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:39.738771Z","caller":"traceutil/trace.go:171","msg":"trace[777492863] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"350.244036ms","start":"2026-03-07T09:58:39.388507Z","end":"2026-03-07T09:58:39.738751Z","steps":["trace[777492863] 'process raft request' (duration: 349.917164ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:39.738786Z","caller":"traceutil/trace.go:171","msg":"trace[1319165262] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"350.132925ms","start":"2026-03-07T09:58:39.388582Z","end":"2026-03-07T09:58:39.738715Z","steps":["trace[1319165262] 'process raft request' (duration: 349.878063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:39.738986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.344788Z","time spent":"393.81714ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:58:39.739001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.388548Z","time spent":"350.349486ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:39.738996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.388473Z","time spent":"350.354847ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:39.739175Z","caller":"traceutil/trace.go:171","msg":"trace[956885484] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"350.146275ms","start":"2026-03-07T09:58:39.389023Z","end":"2026-03-07T09:58:39.739169Z","steps":["trace[956885484] 'process raft request' (duration: 349.46608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:39.739207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.389011Z","time spent":"350.180736ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:39.739247Z","caller":"traceutil/trace.go:171","msg":"trace[904859787] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"350.131485ms","start":"2026-03-07T09:58:39.389101Z","end":"2026-03-07T09:58:39.739232Z","steps":["trace[904859787] 'process raft request' (duration: 349.997004ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:39.739249Z","caller":"traceutil/trace.go:171","msg":"trace[71695736] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:8; }","duration":"329.889966ms","start":"2026-03-07T09:58:39.409342Z","end":"2026-03-07T09:58:39.739232Z","steps":["trace[71695736] 'read index received' (duration: 242.637627ms)","trace[71695736] 'applied index is now lower than readState.Index' (duration: 87.251119ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:58:39.739301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.389084Z","time spent":"350.189195ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:39.739393Z","caller":"traceutil/trace.go:171","msg":"trace[142843089] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"350.236866ms","start":"2026-03-07T09:58:39.389141Z","end":"2026-03-07T09:58:39.739378Z","steps":["trace[142843089] 'process raft request' (duration: 349.996944ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:39.739419Z","caller":"traceutil/trace.go:171","msg":"trace[1336072315] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"350.227666ms","start":"2026-03-07T09:58:39.389187Z","end":"2026-03-07T09:58:39.739415Z","steps":["trace[1336072315] 'process raft request' (duration: 349.994304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:39.739446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.389178Z","time spent":"350.256676ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:39.739453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.389129Z","time spent":"350.304206ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:39.739375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.294954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:39.739508Z","caller":"traceutil/trace.go:171","msg":"trace[1065013231] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"351.441445ms","start":"2026-03-07T09:58:39.38806Z","end":"2026-03-07T09:58:39.739501Z","steps":["trace[1065013231] 'agreement among raft nodes before linearized reading' (duration: 351.269744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:39.7395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.913399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-07T09:58:39.739555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.338978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:39.739573Z","caller":"traceutil/trace.go:171","msg":"trace[2052737018] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:0; response_revision:12; }","duration":"354.371778ms","start":"2026-03-07T09:58:39.385196Z","end":"2026-03-07T09:58:39.739568Z","steps":["trace[2052737018] 'agreement among raft nodes before linearized reading' (duration: 354.348498ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:39.739559Z","caller":"traceutil/trace.go:171","msg":"trace[486392102] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:12; }","duration":"352.03447ms","start":"2026-03-07T09:58:39.387509Z","end":"2026-03-07T09:58:39.739543Z","steps":["trace[486392102] 'agreement among raft nodes before linearized reading' (duration: 351.835819ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:39.739567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.769448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-07T09:58:39.739584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.385187Z","time spent":"354.394028ms","remote":"127.0.0.1:38566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"info","ts":"2026-03-07T09:58:39.739597Z","caller":"traceutil/trace.go:171","msg":"trace[1905607505] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"370.835358ms","start":"2026-03-07T09:58:39.368753Z","end":"2026-03-07T09:58:39.739588Z","steps":["trace[1905607505] 'agreement among raft nodes before linearized reading' (duration: 370.786788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:39.739636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.36874Z","time spent":"370.890089ms","remote":"127.0.0.1:38624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-07T09:58:39.739622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.387449Z","time spent":"352.139271ms","remote":"127.0.0.1:38600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-03-07T09:58:39.739739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.984833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-03-07T09:58:39.739754Z","caller":"traceutil/trace.go:171","msg":"trace[2081557130] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12; }","duration":"327.030363ms","start":"2026-03-07T09:58:39.412718Z","end":"2026-03-07T09:58:39.739749Z","steps":["trace[2081557130] 'agreement among raft nodes before linearized reading' (duration: 326.999932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:39.739768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.412704Z","time spent":"327.059903ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-07T09:58:39.739525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.388052Z","time spent":"351.468645ms","remote":"127.0.0.1:38624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-07T09:58:39.73981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.049972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:39.73982Z","caller":"traceutil/trace.go:171","msg":"trace[733832575] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:12; }","duration":"275.080502ms","start":"2026-03-07T09:58:39.464737Z","end":"2026-03-07T09:58:39.739817Z","steps":["trace[733832575] 'agreement among raft nodes before linearized reading' (duration: 275.063352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:39.739855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.644716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:39.739866Z","caller":"traceutil/trace.go:171","msg":"trace[551800500] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"299.677027ms","start":"2026-03-07T09:58:39.440186Z","end":"2026-03-07T09:58:39.739863Z","steps":["trace[551800500] 'agreement among raft nodes before linearized reading' (duration: 299.661096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.147354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.752092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-07T09:58:40.147636Z","caller":"traceutil/trace.go:171","msg":"trace[84189609] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"404.171192ms","start":"2026-03-07T09:58:39.743437Z","end":"2026-03-07T09:58:40.147608Z","steps":["trace[84189609] 'process raft request' (duration: 211.894685ms)","trace[84189609] 'compare' (duration: 191.262089ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:58:40.147965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.743429Z","time spent":"404.330623ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.148501Z","caller":"traceutil/trace.go:171","msg":"trace[1448429601] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"404.895438ms","start":"2026-03-07T09:58:39.743555Z","end":"2026-03-07T09:58:40.14845Z","steps":["trace[1448429601] 'process raft request' (duration: 404.626775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.148724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.74355Z","time spent":"405.062669ms","remote":"127.0.0.1:38886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.148714Z","caller":"traceutil/trace.go:171","msg":"trace[1749171286] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"345.336106ms","start":"2026-03-07T09:58:39.803345Z","end":"2026-03-07T09:58:40.148681Z","steps":["trace[1749171286] 'process raft request' (duration: 345.291906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.148893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.803325Z","time spent":"345.479487ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.149276Z","caller":"traceutil/trace.go:171","msg":"trace[2099276842] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"404.251052ms","start":"2026-03-07T09:58:39.745014Z","end":"2026-03-07T09:58:40.149265Z","steps":["trace[2099276842] 'process raft request' (duration: 403.357035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.149338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.745007Z","time spent":"404.304252ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.149472Z","caller":"traceutil/trace.go:171","msg":"trace[1276380682] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"403.801478ms","start":"2026-03-07T09:58:39.745661Z","end":"2026-03-07T09:58:40.149462Z","steps":["trace[1276380682] 'process raft request' (duration: 402.927502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.149524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.745646Z","time spent":"403.856108ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.149613Z","caller":"traceutil/trace.go:171","msg":"trace[1751956844] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:16; }","duration":"377.673311ms","start":"2026-03-07T09:58:39.77193Z","end":"2026-03-07T09:58:40.149603Z","steps":["trace[1751956844] 'read index received' (duration: 183.428929ms)","trace[1751956844] 'applied index is now lower than readState.Index' (duration: 194.243442ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:40.149712Z","caller":"traceutil/trace.go:171","msg":"trace[875924369] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"404.564714ms","start":"2026-03-07T09:58:39.745105Z","end":"2026-03-07T09:58:40.14967Z","steps":["trace[875924369] 'process raft request' (duration: 403.323554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.149877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.745085Z","time spent":"404.703215ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:40.149968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.021645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:40.150031Z","caller":"traceutil/trace.go:171","msg":"trace[587390791] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"378.111235ms","start":"2026-03-07T09:58:39.771894Z","end":"2026-03-07T09:58:40.150005Z","steps":["trace[587390791] 'agreement among raft nodes before linearized reading' (duration: 377.926243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.150071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.771876Z","time spent":"378.186686ms","remote":"127.0.0.1:38600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-03-07T09:58:40.150227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.685468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:40.150262Z","caller":"traceutil/trace.go:171","msg":"trace[779023913] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"405.005118ms","start":"2026-03-07T09:58:39.745248Z","end":"2026-03-07T09:58:40.150253Z","steps":["trace[779023913] 'process raft request' (duration: 403.264104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.150325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.745216Z","time spent":"405.075318ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.150318Z","caller":"traceutil/trace.go:171","msg":"trace[1405697217] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"404.689345ms","start":"2026-03-07T09:58:39.745621Z","end":"2026-03-07T09:58:40.15031Z","steps":["trace[1405697217] 'process raft request' (duration: 402.934331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.150401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.74561Z","time spent":"404.761186ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.150219Z","caller":"traceutil/trace.go:171","msg":"trace[167294823] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17; }","duration":"404.939398ms","start":"2026-03-07T09:58:39.745263Z","end":"2026-03-07T09:58:40.150202Z","steps":["trace[167294823] 'process raft request' (duration: 403.211884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.150515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:39.745248Z","time spent":"405.23663ms","remote":"127.0.0.1:38624","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.150265Z","caller":"traceutil/trace.go:171","msg":"trace[1763408671] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"203.767068ms","start":"2026-03-07T09:58:39.946487Z","end":"2026-03-07T09:58:40.150254Z","steps":["trace[1763408671] 'agreement among raft nodes before linearized reading' (duration: 203.717088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.744025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.164498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-07T09:58:40.744184Z","caller":"traceutil/trace.go:171","msg":"trace[1343694922] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"590.371367ms","start":"2026-03-07T09:58:40.153779Z","end":"2026-03-07T09:58:40.744151Z","steps":["trace[1343694922] 'process raft request' (duration: 174.990718ms)","trace[1343694922] 'compare' (duration: 414.990215ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:58:40.744278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.15376Z","time spent":"590.479849ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.752476Z","caller":"traceutil/trace.go:171","msg":"trace[671039567] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"598.565812ms","start":"2026-03-07T09:58:40.153844Z","end":"2026-03-07T09:58:40.75241Z","steps":["trace[671039567] 'process raft request' (duration: 598.405181ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:40.752537Z","caller":"traceutil/trace.go:171","msg":"trace[630765992] linearizableReadLoop","detail":"{readStateIndex:33; appliedIndex:25; }","duration":"597.736076ms","start":"2026-03-07T09:58:40.154787Z","end":"2026-03-07T09:58:40.752523Z","steps":["trace[630765992] 'read index received' (duration: 173.90697ms)","trace[630765992] 'applied index is now lower than readState.Index' (duration: 423.828136ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:40.752646Z","caller":"traceutil/trace.go:171","msg":"trace[1942480564] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"598.676892ms","start":"2026-03-07T09:58:40.153957Z","end":"2026-03-07T09:58:40.752634Z","steps":["trace[1942480564] 'process raft request' (duration: 598.449571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.752697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.153824Z","time spent":"598.769983ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:40.75273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.153949Z","time spent":"598.742513ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:40.752951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.722819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:40.75251Z","caller":"traceutil/trace.go:171","msg":"trace[1261621731] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"598.599662ms","start":"2026-03-07T09:58:40.153878Z","end":"2026-03-07T09:58:40.752478Z","steps":["trace[1261621731] 'process raft request' (duration: 598.492911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.752951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.155059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:40.753Z","caller":"traceutil/trace.go:171","msg":"trace[1325127475] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:29; }","duration":"467.814069ms","start":"2026-03-07T09:58:40.285165Z","end":"2026-03-07T09:58:40.752979Z","steps":["trace[1325127475] 'agreement among raft nodes before linearized reading' (duration: 467.747949ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:40.753018Z","caller":"traceutil/trace.go:171","msg":"trace[1514832390] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:29; }","duration":"598.238799ms","start":"2026-03-07T09:58:40.154765Z","end":"2026-03-07T09:58:40.753004Z","steps":["trace[1514832390] 'agreement among raft nodes before linearized reading' (duration: 598.087868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.753047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.285135Z","time spent":"467.899489ms","remote":"127.0.0.1:38846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-03-07T09:58:40.75306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.154755Z","time spent":"598.29323ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"info","ts":"2026-03-07T09:58:40.753184Z","caller":"traceutil/trace.go:171","msg":"trace[149566867] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"598.770893ms","start":"2026-03-07T09:58:40.154404Z","end":"2026-03-07T09:58:40.753175Z","steps":["trace[149566867] 'process raft request' (duration: 598.027078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.753218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.154397Z","time spent":"598.806554ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.752652Z","caller":"traceutil/trace.go:171","msg":"trace[1684228262] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"598.763953ms","start":"2026-03-07T09:58:40.153878Z","end":"2026-03-07T09:58:40.752642Z","steps":["trace[1684228262] 'process raft request' (duration: 598.443841ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:40.753258Z","caller":"traceutil/trace.go:171","msg":"trace[2060340025] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"598.692423ms","start":"2026-03-07T09:58:40.154551Z","end":"2026-03-07T09:58:40.753243Z","steps":["trace[2060340025] 'process raft request' (duration: 597.920137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.753309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.153843Z","time spent":"599.429658ms","remote":"127.0.0.1:38624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:40.75335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.154541Z","time spent":"598.760553ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:40.753009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.15386Z","time spent":"599.132176ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:40.753447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.148401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:40.753479Z","caller":"traceutil/trace.go:171","msg":"trace[199001277] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:29; }","duration":"468.222982ms","start":"2026-03-07T09:58:40.285245Z","end":"2026-03-07T09:58:40.753468Z","steps":["trace[199001277] 'agreement among raft nodes before linearized reading' (duration: 468.146201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.753501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.285231Z","time spent":"468.266112ms","remote":"127.0.0.1:38854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-03-07T09:58:40.753523Z","caller":"traceutil/trace.go:171","msg":"trace[1162117014] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"598.882074ms","start":"2026-03-07T09:58:40.154626Z","end":"2026-03-07T09:58:40.753508Z","steps":["trace[1162117014] 'process raft request' (duration: 597.875376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.753645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.73817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-07T09:58:40.753648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.154606Z","time spent":"598.962534ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:40.753665Z","caller":"traceutil/trace.go:171","msg":"trace[510946628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29; }","duration":"407.75864ms","start":"2026-03-07T09:58:40.3459Z","end":"2026-03-07T09:58:40.753659Z","steps":["trace[510946628] 'agreement among raft nodes before linearized reading' (duration: 407.69454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:40.753839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.345834Z","time spent":"407.991251ms","remote":"127.0.0.1:38520","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-07T09:58:41.070017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.94034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-07T09:58:41.070541Z","caller":"traceutil/trace.go:171","msg":"trace[1130663653] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"312.276576ms","start":"2026-03-07T09:58:40.758244Z","end":"2026-03-07T09:58:41.07052Z","steps":["trace[1130663653] 'process raft request' (duration: 311.942554ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:41.070563Z","caller":"traceutil/trace.go:171","msg":"trace[510567028] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"312.554058ms","start":"2026-03-07T09:58:40.757908Z","end":"2026-03-07T09:58:41.070462Z","steps":["trace[510567028] 'process raft request' (duration: 200.030953ms)","trace[510567028] 'compare' (duration: 111.754319ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:41.070623Z","caller":"traceutil/trace.go:171","msg":"trace[385255691] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"312.225746ms","start":"2026-03-07T09:58:40.758382Z","end":"2026-03-07T09:58:41.070608Z","steps":["trace[385255691] 'process raft request' (duration: 311.917074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:41.070638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.758231Z","time spent":"312.380507ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:41.070715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.758361Z","time spent":"312.299256ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:41.07079Z","caller":"traceutil/trace.go:171","msg":"trace[37445068] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"312.394117ms","start":"2026-03-07T09:58:40.758345Z","end":"2026-03-07T09:58:41.070739Z","steps":["trace[37445068] 'process raft request' (duration: 311.922293ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:41.070942Z","caller":"traceutil/trace.go:171","msg":"trace[1870000770] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"312.340786ms","start":"2026-03-07T09:58:40.758593Z","end":"2026-03-07T09:58:41.070934Z","steps":["trace[1870000770] 'process raft request' (duration: 311.777812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:41.070997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.758328Z","time spent":"312.573098ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:41.071001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.758583Z","time spent":"312.386187ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:41.071234Z","caller":"traceutil/trace.go:171","msg":"trace[1605876299] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"312.578508ms","start":"2026-03-07T09:58:40.758645Z","end":"2026-03-07T09:58:41.071223Z","steps":["trace[1605876299] 'process raft request' (duration: 311.786833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:41.071338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.758636Z","time spent":"312.631209ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:41.071881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.757883Z","time spent":"312.777369ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:41.073232Z","caller":"traceutil/trace.go:171","msg":"trace[2105350128] linearizableReadLoop","detail":"{readStateIndex:38; appliedIndex:33; }","duration":"314.734495ms","start":"2026-03-07T09:58:40.758474Z","end":"2026-03-07T09:58:41.073208Z","steps":["trace[2105350128] 'read index received' (duration: 32.361482ms)","trace[2105350128] 'applied index is now lower than readState.Index' (duration: 282.371633ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:58:41.073675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.255661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-07T09:58:41.073679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.43ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-07T09:58:41.073696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.977756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:41.073723Z","caller":"traceutil/trace.go:171","msg":"trace[1375485518] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:37; }","duration":"314.338382ms","start":"2026-03-07T09:58:40.759372Z","end":"2026-03-07T09:58:41.073711Z","steps":["trace[1375485518] 'agreement among raft nodes before linearized reading' (duration: 314.204341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:41.07376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.759359Z","time spent":"314.388093ms","remote":"127.0.0.1:38600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" "} {"level":"info","ts":"2026-03-07T09:58:41.073744Z","caller":"traceutil/trace.go:171","msg":"trace[960135891] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:37; }","duration":"233.53244ms","start":"2026-03-07T09:58:40.840192Z","end":"2026-03-07T09:58:41.073724Z","steps":["trace[960135891] 'agreement among raft nodes before linearized reading' (duration: 233.430619ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:41.073736Z","caller":"traceutil/trace.go:171","msg":"trace[886918682] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"314.385363ms","start":"2026-03-07T09:58:40.759339Z","end":"2026-03-07T09:58:41.073724Z","steps":["trace[886918682] 'process raft request' (duration: 311.130118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:41.074309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.759303Z","time spent":"314.901987ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:41.07369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.179878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:41.073761Z","caller":"traceutil/trace.go:171","msg":"trace[1672186981] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:37; }","duration":"279.089506ms","start":"2026-03-07T09:58:40.794649Z","end":"2026-03-07T09:58:41.073738Z","steps":["trace[1672186981] 'agreement among raft nodes before linearized reading' (duration: 278.990875ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:41.076802Z","caller":"traceutil/trace.go:171","msg":"trace[1023649562] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:37; }","duration":"318.334252ms","start":"2026-03-07T09:58:40.758424Z","end":"2026-03-07T09:58:41.076758Z","steps":["trace[1023649562] 'agreement among raft nodes before linearized reading' (duration: 315.107818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:41.076873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:40.75841Z","time spent":"318.448704ms","remote":"127.0.0.1:38852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-03-07T09:58:43.009144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047663261s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-07T09:58:43.00939Z","caller":"traceutil/trace.go:171","msg":"trace[448143851] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:42; }","duration":"1.9294534s","start":"2026-03-07T09:58:41.07992Z","end":"2026-03-07T09:58:43.009373Z","steps":["trace[448143851] 'read index received' (duration: 25.646119ms)","trace[448143851] 'applied index is now lower than readState.Index' (duration: 1.903806241s)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:43.00948Z","caller":"traceutil/trace.go:171","msg":"trace[256738422] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"1.933045848s","start":"2026-03-07T09:58:41.076423Z","end":"2026-03-07T09:58:43.009469Z","steps":["trace[256738422] 'process raft request' (duration: 884.997004ms)","trace[256738422] 'compare' (duration: 1.04754327s)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:58:43.009535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:41.076379Z","time spent":"1.933130708s","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:43.009831Z","caller":"traceutil/trace.go:171","msg":"trace[591010955] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"1.931789198s","start":"2026-03-07T09:58:41.077982Z","end":"2026-03-07T09:58:43.009771Z","steps":["trace[591010955] 'process raft request' (duration: 1.931253064s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.009883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:41.077945Z","time spent":"1.931912539s","remote":"127.0.0.1:38624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:43.009947Z","caller":"traceutil/trace.go:171","msg":"trace[887300261] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"1.928792424s","start":"2026-03-07T09:58:41.081124Z","end":"2026-03-07T09:58:43.009916Z","steps":["trace[887300261] 'process raft request' (duration: 1.92821871s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.010064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.930134876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-07T09:58:43.010077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:41.081113Z","time spent":"1.928911845s","remote":"127.0.0.1:38854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:43.010098Z","caller":"traceutil/trace.go:171","msg":"trace[1218829438] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:43; }","duration":"1.930204378s","start":"2026-03-07T09:58:41.079884Z","end":"2026-03-07T09:58:43.010088Z","steps":["trace[1218829438] 'agreement among raft nodes before linearized reading' (duration: 1.930103136s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.010126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:41.079873Z","time spent":"1.930246058s","remote":"127.0.0.1:38846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-03-07T09:58:43.01026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.870717196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:43.01029Z","caller":"traceutil/trace.go:171","msg":"trace[2105477611] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:43; }","duration":"1.870746836s","start":"2026-03-07T09:58:41.139535Z","end":"2026-03-07T09:58:43.010282Z","steps":["trace[2105477611] 'agreement among raft nodes before linearized reading' (duration: 1.870702276s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.010316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:41.139432Z","time spent":"1.870878036s","remote":"127.0.0.1:38624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-03-07T09:58:43.010402Z","caller":"traceutil/trace.go:171","msg":"trace[1199606934] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"1.930008385s","start":"2026-03-07T09:58:41.080378Z","end":"2026-03-07T09:58:43.010387Z","steps":["trace[1199606934] 'process raft request' (duration: 1.928906406s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.010505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:41.080366Z","time spent":"1.930102995s","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:43.010593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16509937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:43.010625Z","caller":"traceutil/trace.go:171","msg":"trace[1081303608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43; }","duration":"1.16516907s","start":"2026-03-07T09:58:41.845447Z","end":"2026-03-07T09:58:43.010616Z","steps":["trace[1081303608] 'agreement among raft nodes before linearized reading' (duration: 1.16511425s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.010652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:41.845424Z","time spent":"1.165220832s","remote":"127.0.0.1:38522","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-07T09:58:43.010681Z","caller":"traceutil/trace.go:171","msg":"trace[1630534232] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"1.930096095s","start":"2026-03-07T09:58:41.08057Z","end":"2026-03-07T09:58:43.010666Z","steps":["trace[1630534232] 'process raft request' (duration: 1.928748584s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.010772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.660357836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:43.010802Z","caller":"traceutil/trace.go:171","msg":"trace[226753898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43; }","duration":"1.660429177s","start":"2026-03-07T09:58:41.350365Z","end":"2026-03-07T09:58:43.010794Z","steps":["trace[226753898] 'agreement among raft nodes before linearized reading' (duration: 1.660385386s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.010826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:41.350341Z","time spent":"1.660478887s","remote":"127.0.0.1:38520","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-07T09:58:43.010759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:41.080555Z","time spent":"1.930170296s","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T09:58:43.229093Z","caller":"traceutil/trace.go:171","msg":"trace[181583512] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"212.486735ms","start":"2026-03-07T09:58:43.016568Z","end":"2026-03-07T09:58:43.229055Z","steps":["trace[181583512] 'process raft request' (duration: 179.435074ms)","trace[181583512] 'compare' (duration: 32.675908ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:43.22917Z","caller":"traceutil/trace.go:171","msg":"trace[140950379] linearizableReadLoop","detail":"{readStateIndex:49; appliedIndex:47; }","duration":"210.52866ms","start":"2026-03-07T09:58:43.018604Z","end":"2026-03-07T09:58:43.229132Z","steps":["trace[140950379] 'read index received' (duration: 177.288128ms)","trace[140950379] 'applied index is now lower than readState.Index' (duration: 33.239132ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:43.229325Z","caller":"traceutil/trace.go:171","msg":"trace[584962515] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"211.355938ms","start":"2026-03-07T09:58:43.017932Z","end":"2026-03-07T09:58:43.229288Z","steps":["trace[584962515] 'process raft request' (duration: 211.011375ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:43.229395Z","caller":"traceutil/trace.go:171","msg":"trace[627701018] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"209.847855ms","start":"2026-03-07T09:58:43.019489Z","end":"2026-03-07T09:58:43.229337Z","steps":["trace[627701018] 'process raft request' (duration: 209.586593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.229676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.301614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:43.22973Z","caller":"traceutil/trace.go:171","msg":"trace[1367735428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:46; }","duration":"183.398045ms","start":"2026-03-07T09:58:43.046315Z","end":"2026-03-07T09:58:43.229713Z","steps":["trace[1367735428] 'agreement among raft nodes before linearized reading' (duration: 183.152923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.229894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.299866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:43.229933Z","caller":"traceutil/trace.go:171","msg":"trace[1170343639] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:46; }","duration":"211.386866ms","start":"2026-03-07T09:58:43.018537Z","end":"2026-03-07T09:58:43.229924Z","steps":["trace[1170343639] 'agreement among raft nodes before linearized reading' (duration: 211.332936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.230095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.881901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:1 size:338"} {"level":"info","ts":"2026-03-07T09:58:43.230216Z","caller":"traceutil/trace.go:171","msg":"trace[1525114719] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:1; response_revision:46; }","duration":"162.092882ms","start":"2026-03-07T09:58:43.0681Z","end":"2026-03-07T09:58:43.230193Z","steps":["trace[1525114719] 'agreement among raft nodes before linearized reading' (duration: 161.79567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:43.436053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.005726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-07T09:58:43.436139Z","caller":"traceutil/trace.go:171","msg":"trace[1663449681] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:51; }","duration":"106.175267ms","start":"2026-03-07T09:58:43.329943Z","end":"2026-03-07T09:58:43.436118Z","steps":["trace[1663449681] 'range keys from in-memory index tree' (duration: 105.882505ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:43.436225Z","caller":"traceutil/trace.go:171","msg":"trace[1505708692] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"105.884335ms","start":"2026-03-07T09:58:43.330307Z","end":"2026-03-07T09:58:43.436191Z","steps":["trace[1505708692] 'process raft request' (duration: 23.259856ms)","trace[1505708692] 'compare' (duration: 82.339947ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:43.437756Z","caller":"traceutil/trace.go:171","msg":"trace[386422419] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"105.114199ms","start":"2026-03-07T09:58:43.33257Z","end":"2026-03-07T09:58:43.437685Z","steps":["trace[386422419] 'process raft request' (duration: 104.936907ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:43.437827Z","caller":"traceutil/trace.go:171","msg":"trace[709088378] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"105.383831ms","start":"2026-03-07T09:58:43.332424Z","end":"2026-03-07T09:58:43.437808Z","steps":["trace[709088378] 'process raft request' (duration: 104.932977ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:43.619989Z","caller":"traceutil/trace.go:171","msg":"trace[1528859903] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"176.092789ms","start":"2026-03-07T09:58:43.443876Z","end":"2026-03-07T09:58:43.619969Z","steps":["trace[1528859903] 'process raft request' (duration: 175.243783ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:43.620073Z","caller":"traceutil/trace.go:171","msg":"trace[200288476] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"176.044549ms","start":"2026-03-07T09:58:43.443997Z","end":"2026-03-07T09:58:43.620041Z","steps":["trace[200288476] 'process raft request' (duration: 175.904538ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:43.620086Z","caller":"traceutil/trace.go:171","msg":"trace[1754377795] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"175.911308ms","start":"2026-03-07T09:58:43.44415Z","end":"2026-03-07T09:58:43.620062Z","steps":["trace[1754377795] 'process raft request' (duration: 175.868938ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:43.620393Z","caller":"traceutil/trace.go:171","msg":"trace[1970691623] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"176.406231ms","start":"2026-03-07T09:58:43.443977Z","end":"2026-03-07T09:58:43.620383Z","steps":["trace[1970691623] 'process raft request' (duration: 175.964048ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:43.62045Z","caller":"traceutil/trace.go:171","msg":"trace[325892691] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"176.531753ms","start":"2026-03-07T09:58:43.443904Z","end":"2026-03-07T09:58:43.620436Z","steps":["trace[325892691] 'process raft request' (duration: 175.905578ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:43.62098Z","caller":"traceutil/trace.go:171","msg":"trace[1300585463] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"163.693776ms","start":"2026-03-07T09:58:43.457272Z","end":"2026-03-07T09:58:43.620966Z","steps":["trace[1300585463] 'process raft request' (duration: 163.636055ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:44.024348Z","caller":"traceutil/trace.go:171","msg":"trace[15977623] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"102.999572ms","start":"2026-03-07T09:58:43.921321Z","end":"2026-03-07T09:58:44.024321Z","steps":["trace[15977623] 'process raft request' (duration: 102.822651ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:44.024617Z","caller":"traceutil/trace.go:171","msg":"trace[341374696] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"103.719868ms","start":"2026-03-07T09:58:43.920853Z","end":"2026-03-07T09:58:44.024573Z","steps":["trace[341374696] 'process raft request' (duration: 58.602476ms)","trace[341374696] 'compare' (duration: 44.397536ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:44.640158Z","caller":"traceutil/trace.go:171","msg":"trace[963257904] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"114.125089ms","start":"2026-03-07T09:58:44.525984Z","end":"2026-03-07T09:58:44.640109Z","steps":["trace[963257904] 'process raft request' (duration: 33.448362ms)","trace[963257904] 'compare' (duration: 80.391915ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:44.640191Z","caller":"traceutil/trace.go:171","msg":"trace[1211253867] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"114.070849ms","start":"2026-03-07T09:58:44.5261Z","end":"2026-03-07T09:58:44.64017Z","steps":["trace[1211253867] 'process raft request' (duration: 113.947388ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:45.168409Z","caller":"traceutil/trace.go:171","msg":"trace[47779653] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"100.703432ms","start":"2026-03-07T09:58:45.067672Z","end":"2026-03-07T09:58:45.168375Z","steps":["trace[47779653] 'process raft request' (duration: 60.604013ms)","trace[47779653] 'compare' (duration: 39.896638ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:45.40541Z","caller":"traceutil/trace.go:171","msg":"trace[1598845491] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"141.313085ms","start":"2026-03-07T09:58:45.264064Z","end":"2026-03-07T09:58:45.405377Z","steps":["trace[1598845491] 'process raft request' (duration: 141.079143ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:45.438712Z","caller":"traceutil/trace.go:171","msg":"trace[1478945878] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"169.394214ms","start":"2026-03-07T09:58:45.269296Z","end":"2026-03-07T09:58:45.43869Z","steps":["trace[1478945878] 'process raft request' (duration: 169.223393ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:45.951135Z","caller":"traceutil/trace.go:171","msg":"trace[1953661541] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"135.153969ms","start":"2026-03-07T09:58:45.815948Z","end":"2026-03-07T09:58:45.951102Z","steps":["trace[1953661541] 'process raft request' (duration: 45.816632ms)","trace[1953661541] 'compare' (duration: 89.178676ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:46.194588Z","caller":"traceutil/trace.go:171","msg":"trace[963510446] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"181.892966ms","start":"2026-03-07T09:58:46.012642Z","end":"2026-03-07T09:58:46.194535Z","steps":["trace[963510446] 'process raft request' (duration: 125.431528ms)","trace[963510446] 'compare' (duration: 56.083846ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:58:47.173994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.573259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:58:47.174092Z","caller":"traceutil/trace.go:171","msg":"trace[221407920] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:0; response_revision:202; }","duration":"202.73023ms","start":"2026-03-07T09:58:46.97134Z","end":"2026-03-07T09:58:47.17407Z","steps":["trace[221407920] 'range keys from in-memory index tree' (duration: 202.443758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:47.746887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529633644306121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-07T09:58:48.248025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529633644306121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-07T09:58:48.748426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529633644306121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-07T09:58:48.791454Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.580318615s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-07T09:58:49.246709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000361919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-07T09:58:49.246825Z","caller":"traceutil/trace.go:171","msg":"trace[1137655766] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00051727s","start":"2026-03-07T09:58:47.246285Z","end":"2026-03-07T09:58:49.246802Z","steps":["trace[1137655766] 'agreement among raft nodes before linearized reading' (duration: 2.000381579s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:49.246877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:47.246257Z","time spent":"2.00061012s","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-07T09:58:50.350976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000660141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-07T09:58:50.351101Z","caller":"traceutil/trace.go:171","msg":"trace[928014544] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000840502s","start":"2026-03-07T09:58:48.350233Z","end":"2026-03-07T09:58:50.351073Z","steps":["trace[928014544] 'agreement among raft nodes before linearized reading' (duration: 2.000692661s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:50.351153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:48.350211Z","time spent":"2.000930472s","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/07 09:58:50 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-07T09:58:51.2479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000300765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-07T09:58:51.247984Z","caller":"traceutil/trace.go:171","msg":"trace[519278574] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000431855s","start":"2026-03-07T09:58:49.247531Z","end":"2026-03-07T09:58:51.247962Z","steps":["trace[519278574] 'agreement among raft nodes before linearized reading' (duration: 2.000333345s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:51.248032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:49.247511Z","time spent":"2.000510967s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/07 09:58:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-07T09:58:51.846776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.055021521s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-07T09:58:51.846908Z","caller":"traceutil/trace.go:171","msg":"trace[102944998] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"4.63580845s","start":"2026-03-07T09:58:47.211066Z","end":"2026-03-07T09:58:51.846875Z","steps":["trace[102944998] 'process raft request' (duration: 1.580620588s)","trace[102944998] 'compare' (duration: 3.054709929s)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:51.846872Z","caller":"traceutil/trace.go:171","msg":"trace[2120225494] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"4.600538501s","start":"2026-03-07T09:58:47.246317Z","end":"2026-03-07T09:58:51.846856Z","steps":["trace[2120225494] 'read index received' (duration: 1.545321569s)","trace[2120225494] 'applied index is now lower than readState.Index' (duration: 3.055215462s)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:58:51.847051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:47.211052Z","time spent":"4.635937771s","remote":"127.0.0.1:38852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":732,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:52.347492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529633644306125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-07T09:58:52.840313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00010473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-07T09:58:52.840415Z","caller":"traceutil/trace.go:171","msg":"trace[1867646083] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000243421s","start":"2026-03-07T09:58:50.840138Z","end":"2026-03-07T09:58:52.840381Z","steps":["trace[1867646083] 'agreement among raft nodes before linearized reading' (duration: 2.00013813s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:52.840478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:50.840114Z","time spent":"2.000351162s","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/07 09:58:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-07T09:58:52.848693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529633644306125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-07T09:58:53.0246Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.895980098s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-07T09:58:53.346726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000063457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-07T09:58:53.346819Z","caller":"traceutil/trace.go:171","msg":"trace[2103520004] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000201037s","start":"2026-03-07T09:58:51.346591Z","end":"2026-03-07T09:58:53.346792Z","steps":["trace[2103520004] 'agreement among raft nodes before linearized reading' (duration: 2.000093447s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:53.346869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:51.346572Z","time spent":"2.000287558s","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/07 09:58:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-07T09:58:53.851499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000697029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-07T09:58:53.85184Z","caller":"traceutil/trace.go:171","msg":"trace[702507192] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00084564s","start":"2026-03-07T09:58:51.850719Z","end":"2026-03-07T09:58:53.851565Z","steps":["trace[702507192] 'agreement among raft nodes before linearized reading' (duration: 2.000712669s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:53.851915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:51.85071Z","time spent":"2.001190273s","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/07 09:58:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-07T09:58:54.534287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999315664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-07T09:58:54.534384Z","caller":"traceutil/trace.go:171","msg":"trace[1678302760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999471216s","start":"2026-03-07T09:58:49.534889Z","end":"2026-03-07T09:58:54.53436Z","steps":["trace[1678302760] 'agreement among raft nodes before linearized reading' (duration: 4.999347015s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:54.534448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:49.534869Z","time spent":"4.999556286s","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-07T09:58:55.023617Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.998857538s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-07T09:58:55.034671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.009745072s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-07T09:58:55.034911Z","caller":"traceutil/trace.go:171","msg":"trace[1595072369] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:210; }","duration":"3.187890956s","start":"2026-03-07T09:58:51.847004Z","end":"2026-03-07T09:58:55.034895Z","steps":["trace[1595072369] 'read index received' (duration: 1.177858312s)","trace[1595072369] 'applied index is now lower than readState.Index' (duration: 2.010031524s)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:55.03507Z","caller":"traceutil/trace.go:171","msg":"trace[213875100] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"2.191538043s","start":"2026-03-07T09:58:52.843493Z","end":"2026-03-07T09:58:55.035031Z","steps":["trace[213875100] 'process raft request' (duration: 2.191367382s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:55.035178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.024200723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6959"} {"level":"warn","ts":"2026-03-07T09:58:55.035233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:52.843438Z","time spent":"2.191722684s","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-07T09:58:55.035315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.279201976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-07T09:58:55.035309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.279961403s","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-07T09:58:55.035385Z","caller":"traceutil/trace.go:171","msg":"trace[1315721189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:208; }","duration":"7.279310218s","start":"2026-03-07T09:58:47.756046Z","end":"2026-03-07T09:58:55.035356Z","steps":["trace[1315721189] 'agreement among raft nodes before linearized reading' (duration: 7.279184597s)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:55.035395Z","caller":"traceutil/trace.go:171","msg":"trace[1681767604] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"4.906796748s","start":"2026-03-07T09:58:50.128466Z","end":"2026-03-07T09:58:55.035263Z","steps":["trace[1681767604] 'process raft request' (duration: 2.896377702s)","trace[1681767604] 'compare' (duration: 2.008242461s)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:55.035401Z","caller":"traceutil/trace.go:171","msg":"trace[964882108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:208; }","duration":"7.280097934s","start":"2026-03-07T09:58:47.755281Z","end":"2026-03-07T09:58:55.035379Z","steps":["trace[964882108] 'agreement among raft nodes before linearized reading' (duration: 7.279887302s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:55.035445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:47.756014Z","time spent":"7.279403438s","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-07T09:58:55.035493Z","caller":"traceutil/trace.go:171","msg":"trace[197305661] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"4.284021365s","start":"2026-03-07T09:58:50.751261Z","end":"2026-03-07T09:58:55.035282Z","steps":["trace[197305661] 'process raft request' (duration: 4.283525552s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:55.035562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:50.751237Z","time spent":"4.284284697s","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:58:55.035453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:47.755261Z","time spent":"7.280178184s","remote":"127.0.0.1:38776","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-07T09:58:55.03533Z","caller":"traceutil/trace.go:171","msg":"trace[1093982529] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:208; }","duration":"4.024278725s","start":"2026-03-07T09:58:51.010931Z","end":"2026-03-07T09:58:55.03521Z","steps":["trace[1093982529] 'agreement among raft nodes before linearized reading' (duration: 4.024189464s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:55.035755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:51.010914Z","time spent":"4.024832938s","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6983,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-07T09:58:55.036294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:50.128439Z","time spent":"4.90701126s","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:58:55.041108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.190553204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:58:55.041206Z","caller":"traceutil/trace.go:171","msg":"trace[1858568573] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"1.439548086s","start":"2026-03-07T09:58:53.601635Z","end":"2026-03-07T09:58:55.041183Z","steps":["trace[1858568573] 'process raft request' (duration: 1.439218494s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:55.041414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:53.601609Z","time spent":"1.439763107s","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:58:55.041278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.603994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:58:55.041578Z","caller":"traceutil/trace.go:171","msg":"trace[1815684392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:209; }","duration":"183.938547ms","start":"2026-03-07T09:58:54.857622Z","end":"2026-03-07T09:58:55.041561Z","steps":["trace[1815684392] 'agreement among raft nodes before linearized reading' (duration: 183.625735ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:55.04119Z","caller":"traceutil/trace.go:171","msg":"trace[136028] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:0; response_revision:209; }","duration":"3.190643665s","start":"2026-03-07T09:58:51.850525Z","end":"2026-03-07T09:58:55.041169Z","steps":["trace[136028] 'agreement among raft nodes before linearized reading' (duration: 3.190501564s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:55.041644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:51.850467Z","time spent":"3.191166908s","remote":"127.0.0.1:38852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" "} {"level":"warn","ts":"2026-03-07T09:58:55.041318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595333137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:58:55.04177Z","caller":"traceutil/trace.go:171","msg":"trace[726893480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:209; }","duration":"1.595826761s","start":"2026-03-07T09:58:53.44593Z","end":"2026-03-07T09:58:55.041757Z","steps":["trace[726893480] 'agreement among raft nodes before linearized reading' (duration: 1.595347806s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:55.041808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:58:53.445905Z","time spent":"1.595895511s","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-07T09:58:55.950997Z","caller":"traceutil/trace.go:171","msg":"trace[857054493] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"105.65039ms","start":"2026-03-07T09:58:55.845317Z","end":"2026-03-07T09:58:55.950968Z","steps":["trace[857054493] 'compare' (duration: 97.893467ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:58.340458Z","caller":"traceutil/trace.go:171","msg":"trace[2038904819] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"279.793746ms","start":"2026-03-07T09:58:58.06057Z","end":"2026-03-07T09:58:58.340363Z","steps":["trace[2038904819] 'process raft request' (duration: 258.681903ms)","trace[2038904819] 'compare' (duration: 20.858381ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:58.34053Z","caller":"traceutil/trace.go:171","msg":"trace[1274572366] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"260.124183ms","start":"2026-03-07T09:58:58.08038Z","end":"2026-03-07T09:58:58.340504Z","steps":["trace[1274572366] 'process raft request' (duration: 259.885271ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:58.340648Z","caller":"traceutil/trace.go:171","msg":"trace[830024150] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"221.542601ms","start":"2026-03-07T09:58:58.119087Z","end":"2026-03-07T09:58:58.34063Z","steps":["trace[830024150] 'process raft request' (duration: 221.48339ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:58.340878Z","caller":"traceutil/trace.go:171","msg":"trace[901244072] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"233.820354ms","start":"2026-03-07T09:58:58.107052Z","end":"2026-03-07T09:58:58.340872Z","steps":["trace[901244072] 'process raft request' (duration: 233.412862ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:58.340916Z","caller":"traceutil/trace.go:171","msg":"trace[1135859297] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"233.530402ms","start":"2026-03-07T09:58:58.107374Z","end":"2026-03-07T09:58:58.340904Z","steps":["trace[1135859297] 'process raft request' (duration: 233.159639ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:58.341021Z","caller":"traceutil/trace.go:171","msg":"trace[2050286577] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"233.967495ms","start":"2026-03-07T09:58:58.107037Z","end":"2026-03-07T09:58:58.341004Z","steps":["trace[2050286577] 'process raft request' (duration: 233.458932ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:58.341077Z","caller":"traceutil/trace.go:171","msg":"trace[820385403] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"232.834448ms","start":"2026-03-07T09:58:58.108228Z","end":"2026-03-07T09:58:58.341062Z","steps":["trace[820385403] 'process raft request' (duration: 232.322304ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:58.341101Z","caller":"traceutil/trace.go:171","msg":"trace[995598502] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"233.941675ms","start":"2026-03-07T09:58:58.107121Z","end":"2026-03-07T09:58:58.341062Z","steps":["trace[995598502] 'process raft request' (duration: 233.391171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:59.065976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.806526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-07T09:58:59.06615Z","caller":"traceutil/trace.go:171","msg":"trace[50061089] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:320; }","duration":"153.79156ms","start":"2026-03-07T09:58:58.912331Z","end":"2026-03-07T09:58:59.066123Z","steps":["trace[50061089] 'read index received' (duration: 29.793932ms)","trace[50061089] 'applied index is now lower than readState.Index' (duration: 123.996778ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:59.066219Z","caller":"traceutil/trace.go:171","msg":"trace[1066619865] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"161.686163ms","start":"2026-03-07T09:58:58.904523Z","end":"2026-03-07T09:58:59.066209Z","steps":["trace[1066619865] 'process raft request' (duration: 37.590995ms)","trace[1066619865] 'compare' (duration: 123.691696ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:59.066351Z","caller":"traceutil/trace.go:171","msg":"trace[1984014951] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"158.721842ms","start":"2026-03-07T09:58:58.907599Z","end":"2026-03-07T09:58:59.066321Z","steps":["trace[1984014951] 'process raft request' (duration: 158.47245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:58:59.066522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.190412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:58:59.066555Z","caller":"traceutil/trace.go:171","msg":"trace[316392085] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:310; }","duration":"154.263592ms","start":"2026-03-07T09:58:58.912282Z","end":"2026-03-07T09:58:59.066545Z","steps":["trace[316392085] 'agreement among raft nodes before linearized reading' (duration: 154.182692ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:58:59.32026Z","caller":"traceutil/trace.go:171","msg":"trace[889721113] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"111.782513ms","start":"2026-03-07T09:58:59.208457Z","end":"2026-03-07T09:58:59.320239Z","steps":["trace[889721113] 'process raft request' (duration: 96.151347ms)","trace[889721113] 'compare' (duration: 15.215213ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:58:59.320592Z","caller":"traceutil/trace.go:171","msg":"trace[249799233] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"111.954644ms","start":"2026-03-07T09:58:59.20863Z","end":"2026-03-07T09:58:59.320584Z","steps":["trace[249799233] 'process raft request' (duration: 111.34843ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:59:23.624241Z","caller":"traceutil/trace.go:171","msg":"trace[243106940] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"353.20296ms","start":"2026-03-07T09:59:23.271012Z","end":"2026-03-07T09:59:23.624215Z","steps":["trace[243106940] 'read index received' (duration: 352.932399ms)","trace[243106940] 'applied index is now lower than readState.Index' (duration: 269.611µs)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:59:23.624299Z","caller":"traceutil/trace.go:171","msg":"trace[148222446] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"379.074708ms","start":"2026-03-07T09:59:23.245196Z","end":"2026-03-07T09:59:23.624271Z","steps":["trace[148222446] 'process raft request' (duration: 378.821256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:23.624414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.39822ms","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-07T09:59:23.624455Z","caller":"traceutil/trace.go:171","msg":"trace[968815778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"278.455251ms","start":"2026-03-07T09:59:23.34599Z","end":"2026-03-07T09:59:23.624445Z","steps":["trace[968815778] 'agreement among raft nodes before linearized reading' (duration: 278.36832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:23.624495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:23.245167Z","time spent":"379.262729ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:59:23.624465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.433452ms","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-07T09:59:23.624586Z","caller":"traceutil/trace.go:171","msg":"trace[1224689692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"353.635183ms","start":"2026-03-07T09:59:23.270926Z","end":"2026-03-07T09:59:23.624561Z","steps":["trace[1224689692] 'agreement among raft nodes before linearized reading' (duration: 353.385352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:23.624601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.917068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-07T09:59:23.624659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.469191ms","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-07T09:59:23.624687Z","caller":"traceutil/trace.go:171","msg":"trace[2041091682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"202.530581ms","start":"2026-03-07T09:59:23.422148Z","end":"2026-03-07T09:59:23.624678Z","steps":["trace[2041091682] 'agreement among raft nodes before linearized reading' (duration: 202.469051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:23.624642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:23.270876Z","time spent":"353.754224ms","remote":"127.0.0.1:38776","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-07T09:59:23.624696Z","caller":"traceutil/trace.go:171","msg":"trace[573111177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"167.008768ms","start":"2026-03-07T09:59:23.457667Z","end":"2026-03-07T09:59:23.624675Z","steps":["trace[573111177] 'agreement among raft nodes before linearized reading' (duration: 166.874637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:24.289192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.528829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-03-07T09:59:24.289299Z","caller":"traceutil/trace.go:171","msg":"trace[814857870] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:538; }","duration":"287.656871ms","start":"2026-03-07T09:59:24.001606Z","end":"2026-03-07T09:59:24.289263Z","steps":["trace[814857870] 'range keys from in-memory index tree' (duration: 287.452989ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:59:29.8987Z","caller":"traceutil/trace.go:171","msg":"trace[900926991] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"221.10187ms","start":"2026-03-07T09:59:29.677573Z","end":"2026-03-07T09:59:29.898675Z","steps":["trace[900926991] 'read index received' (duration: 90.494455ms)","trace[900926991] 'applied index is now lower than readState.Index' (duration: 130.606365ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:59:29.898856Z","caller":"traceutil/trace.go:171","msg":"trace[1168751781] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"223.719595ms","start":"2026-03-07T09:59:29.675068Z","end":"2026-03-07T09:59:29.898787Z","steps":["trace[1168751781] 'process raft request' (duration: 223.466723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:29.898921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.339512ms","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-07T09:59:29.89894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.773888ms","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-07T09:59:29.898997Z","caller":"traceutil/trace.go:171","msg":"trace[1612809258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"220.878788ms","start":"2026-03-07T09:59:29.678104Z","end":"2026-03-07T09:59:29.898983Z","steps":["trace[1612809258] 'agreement among raft nodes before linearized reading' (duration: 220.702037ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:59:29.898997Z","caller":"traceutil/trace.go:171","msg":"trace[1135561788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"221.447432ms","start":"2026-03-07T09:59:29.677531Z","end":"2026-03-07T09:59:29.898979Z","steps":["trace[1135561788] 'agreement among raft nodes before linearized reading' (duration: 221.262251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:30.546349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.981417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-07T09:59:30.546452Z","caller":"traceutil/trace.go:171","msg":"trace[610886105] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"643.557506ms","start":"2026-03-07T09:59:29.902878Z","end":"2026-03-07T09:59:30.546436Z","steps":["trace[610886105] 'process raft request' (duration: 351.411598ms)","trace[610886105] 'compare' (duration: 291.769536ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:59:30.546524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:29.902853Z","time spent":"643.636576ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T09:59:30.59804Z","caller":"traceutil/trace.go:171","msg":"trace[1796413559] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"503.053493ms","start":"2026-03-07T09:59:30.094962Z","end":"2026-03-07T09:59:30.598015Z","steps":["trace[1796413559] 'read index received' (duration: 159.278221ms)","trace[1796413559] 'applied index is now lower than readState.Index' (duration: 343.774172ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T09:59:30.598171Z","caller":"traceutil/trace.go:171","msg":"trace[1709106037] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"693.50461ms","start":"2026-03-07T09:59:29.904648Z","end":"2026-03-07T09:59:30.598153Z","steps":["trace[1709106037] 'process raft request' (duration: 693.243429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:30.598227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.248724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s4fkf\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-07T09:59:30.598273Z","caller":"traceutil/trace.go:171","msg":"trace[647071421] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s4fkf; range_end:; response_count:1; response_revision:579; }","duration":"503.352285ms","start":"2026-03-07T09:59:30.094909Z","end":"2026-03-07T09:59:30.598261Z","steps":["trace[647071421] 'agreement among raft nodes before linearized reading' (duration: 503.239284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:30.59831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:30.094843Z","time spent":"503.459545ms","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-s4fkf\" "} {"level":"warn","ts":"2026-03-07T09:59:30.59832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:29.904631Z","time spent":"693.633951ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:59:30.598785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.026971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:59:30.59883Z","caller":"traceutil/trace.go:171","msg":"trace[2110493614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"178.111602ms","start":"2026-03-07T09:59:30.420708Z","end":"2026-03-07T09:59:30.59882Z","steps":["trace[2110493614] 'agreement among raft nodes before linearized reading' (duration: 178.048091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:32.27241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529633644306905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-07T09:59:32.773256Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529633644306905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-07T09:59:32.789433Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.178088983s","expected-duration":"1s"} {"level":"info","ts":"2026-03-07T09:59:32.789968Z","caller":"traceutil/trace.go:171","msg":"trace[1390686663] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"2.178693827s","start":"2026-03-07T09:59:30.611231Z","end":"2026-03-07T09:59:32.789925Z","steps":["trace[1390686663] 'process raft request' (duration: 2.178512446s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:32.79009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:30.611213Z","time spent":"2.178811778s","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:59:33.20384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.537408ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-07T09:59:33.204145Z","caller":"traceutil/trace.go:171","msg":"trace[1896676455] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"1.432599924s","start":"2026-03-07T09:59:31.771414Z","end":"2026-03-07T09:59:33.204014Z","steps":["trace[1896676455] 'read index received' (duration: 1.018252231s)","trace[1896676455] 'applied index is now lower than readState.Index' (duration: 414.345203ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:59:33.204252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432808615s","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-07T09:59:33.204336Z","caller":"traceutil/trace.go:171","msg":"trace[1834430102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"1.432936217s","start":"2026-03-07T09:59:31.77138Z","end":"2026-03-07T09:59:33.204316Z","steps":["trace[1834430102] 'agreement among raft nodes before linearized reading' (duration: 1.432728505s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:33.204388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:31.771358Z","time spent":"1.433019718s","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-07T09:59:33.204667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301897294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-07T09:59:33.204676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.805541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:59:33.204722Z","caller":"traceutil/trace.go:171","msg":"trace[950321138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"782.887371ms","start":"2026-03-07T09:59:32.421822Z","end":"2026-03-07T09:59:33.20471Z","steps":["trace[950321138] 'agreement among raft nodes before linearized reading' (duration: 782.826511ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:59:33.20472Z","caller":"traceutil/trace.go:171","msg":"trace[1336219377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"1.302006595s","start":"2026-03-07T09:59:31.902698Z","end":"2026-03-07T09:59:33.204704Z","steps":["trace[1336219377] 'agreement among raft nodes before linearized reading' (duration: 1.301793154s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:33.20475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:32.421805Z","time spent":"782.939282ms","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-07T09:59:33.204767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:31.902663Z","time spent":"1.302094006s","remote":"127.0.0.1:38776","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-07T09:59:33.204787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.299485ms","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-07T09:59:33.204836Z","caller":"traceutil/trace.go:171","msg":"trace[1295013738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"654.384895ms","start":"2026-03-07T09:59:32.550437Z","end":"2026-03-07T09:59:33.204822Z","steps":["trace[1295013738] 'agreement among raft nodes before linearized reading' (duration: 654.199194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:33.204869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:32.550419Z","time spent":"654.442465ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-07T09:59:33.205152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.361706ms","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-07T09:59:33.205191Z","caller":"traceutil/trace.go:171","msg":"trace[1646217929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"603.423616ms","start":"2026-03-07T09:59:32.601755Z","end":"2026-03-07T09:59:33.205178Z","steps":["trace[1646217929] 'agreement among raft nodes before linearized reading' (duration: 603.257205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:33.205223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:32.601743Z","time spent":"603.472306ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-07T09:59:34.705632Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.49682985s","expected-duration":"1s"} {"level":"info","ts":"2026-03-07T09:59:34.706034Z","caller":"traceutil/trace.go:171","msg":"trace[950007824] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.497419094s","start":"2026-03-07T09:59:33.208593Z","end":"2026-03-07T09:59:34.706012Z","steps":["trace[950007824] 'process raft request' (duration: 1.497202093s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:34.70617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:33.208546Z","time spent":"1.497550996s","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:59:36.421962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00018259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-07T09:59:36.422031Z","caller":"traceutil/trace.go:171","msg":"trace[152467732] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00030725s","start":"2026-03-07T09:59:34.421708Z","end":"2026-03-07T09:59:36.422015Z","steps":["trace[152467732] 'agreement among raft nodes before linearized reading' (duration: 2.00021573s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:36.422065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:34.421689Z","time spent":"2.00036959s","remote":"127.0.0.1:38520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-07T09:59:36.565245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364173517s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-07T09:59:36.5655Z","caller":"traceutil/trace.go:171","msg":"trace[172316594] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"3.356539604s","start":"2026-03-07T09:59:33.208938Z","end":"2026-03-07T09:59:36.565478Z","steps":["trace[172316594] 'process raft request' (duration: 3.356430893s)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:59:36.565526Z","caller":"traceutil/trace.go:171","msg":"trace[884715396] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"3.356680265s","start":"2026-03-07T09:59:33.20878Z","end":"2026-03-07T09:59:36.56546Z","steps":["trace[884715396] 'process raft request' (duration: 1.992197606s)","trace[884715396] 'compare' (duration: 1.363885865s)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:59:36.565595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:33.208869Z","time spent":"3.356679165s","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T09:59:36.565679Z","caller":"traceutil/trace.go:171","msg":"trace[201193396] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"971.580925ms","start":"2026-03-07T09:59:35.594077Z","end":"2026-03-07T09:59:36.565658Z","steps":["trace[201193396] 'process raft request' (duration: 971.511714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:36.56571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:33.208764Z","time spent":"3.356856265s","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:59:36.5658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:35.594053Z","time spent":"971.680675ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T09:59:36.565831Z","caller":"traceutil/trace.go:171","msg":"trace[89245494] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"3.356724975s","start":"2026-03-07T09:59:33.209077Z","end":"2026-03-07T09:59:36.565802Z","steps":["trace[89245494] 'process raft request' (duration: 3.356363563s)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:59:36.565851Z","caller":"traceutil/trace.go:171","msg":"trace[714553578] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"2.144081619s","start":"2026-03-07T09:59:34.42175Z","end":"2026-03-07T09:59:36.565832Z","steps":["trace[714553578] 'read index received' (duration: 284.082303ms)","trace[714553578] 'applied index is now lower than readState.Index' (duration: 1.859988385s)"],"step_count":2} {"level":"warn","ts":"2026-03-07T09:59:36.565984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:33.209068Z","time spent":"3.356827817s","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T09:59:36.566006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.985612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:59:36.566052Z","caller":"traceutil/trace.go:171","msg":"trace[965419674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"143.055214ms","start":"2026-03-07T09:59:36.422984Z","end":"2026-03-07T09:59:36.566039Z","steps":["trace[965419674] 'agreement among raft nodes before linearized reading' (duration: 142.977693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:36.566129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.717951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-07T09:59:36.566204Z","caller":"traceutil/trace.go:171","msg":"trace[354498860] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:585; }","duration":"816.821751ms","start":"2026-03-07T09:59:35.749361Z","end":"2026-03-07T09:59:36.566183Z","steps":["trace[354498860] 'agreement among raft nodes before linearized reading' (duration: 816.6043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:36.566249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:35.749344Z","time spent":"816.892693ms","remote":"127.0.0.1:38566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-03-07T09:59:38.635747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.142486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:59:38.635828Z","caller":"traceutil/trace.go:171","msg":"trace[1008390800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"213.266277ms","start":"2026-03-07T09:59:38.422542Z","end":"2026-03-07T09:59:38.635808Z","steps":["trace[1008390800] 'range keys from in-memory index tree' (duration: 213.068206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:38.636129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.375328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:59:38.636248Z","caller":"traceutil/trace.go:171","msg":"trace[1424566538] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:615; }","duration":"325.541349ms","start":"2026-03-07T09:59:38.31068Z","end":"2026-03-07T09:59:38.636221Z","steps":["trace[1424566538] 'range keys from in-memory index tree' (duration: 325.289038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T09:59:38.636314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T09:59:38.310659Z","time spent":"325.638269ms","remote":"127.0.0.1:38572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-03-07T09:59:38.636479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.988094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T09:59:38.636674Z","caller":"traceutil/trace.go:171","msg":"trace[821361991] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:615; }","duration":"125.716939ms","start":"2026-03-07T09:59:38.51091Z","end":"2026-03-07T09:59:38.636627Z","steps":["trace[821361991] 'range keys from in-memory index tree' (duration: 124.910024ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T09:59:41.508615Z","caller":"traceutil/trace.go:171","msg":"trace[1168187623] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"169.242395ms","start":"2026-03-07T09:59:41.339343Z","end":"2026-03-07T09:59:41.508585Z","steps":["trace[1168187623] 'process raft request' (duration: 169.021494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T10:01:05.340025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.578599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-07T10:01:05.341625Z","caller":"traceutil/trace.go:171","msg":"trace[1035544273] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"155.814897ms","start":"2026-03-07T10:01:05.185753Z","end":"2026-03-07T10:01:05.341568Z","steps":["trace[1035544273] 'process raft request' (duration: 51.55788ms)","trace[1035544273] 'compare' (duration: 102.356237ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T10:01:05.341708Z","caller":"traceutil/trace.go:171","msg":"trace[1197303117] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"155.683447ms","start":"2026-03-07T10:01:05.185998Z","end":"2026-03-07T10:01:05.341682Z","steps":["trace[1197303117] 'process raft request' (duration: 155.546226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T10:01:06.868068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.237775ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T10:01:06.8682Z","caller":"traceutil/trace.go:171","msg":"trace[1069601933] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1023; }","duration":"102.391076ms","start":"2026-03-07T10:01:06.765791Z","end":"2026-03-07T10:01:06.868182Z","steps":["trace[1069601933] 'range keys from in-memory index tree' (duration: 102.215875ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T10:01:07.379746Z","caller":"traceutil/trace.go:171","msg":"trace[2112111229] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"194.606889ms","start":"2026-03-07T10:01:07.185116Z","end":"2026-03-07T10:01:07.379722Z","steps":["trace[2112111229] 'read index received' (duration: 194.377708ms)","trace[2112111229] 'applied index is now lower than readState.Index' (duration: 228.141µs)"],"step_count":2} {"level":"info","ts":"2026-03-07T10:01:07.37986Z","caller":"traceutil/trace.go:171","msg":"trace[1225797086] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"241.485964ms","start":"2026-03-07T10:01:07.138364Z","end":"2026-03-07T10:01:07.37985Z","steps":["trace[1225797086] 'process raft request' (duration: 241.205942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T10:01:07.380116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.985281ms","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-07T10:01:07.380181Z","caller":"traceutil/trace.go:171","msg":"trace[1735794677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1024; }","duration":"195.103163ms","start":"2026-03-07T10:01:07.185062Z","end":"2026-03-07T10:01:07.380165Z","steps":["trace[1735794677] 'agreement among raft nodes before linearized reading' (duration: 194.919262ms)"],"step_count":1}