{"level":"warn","ts":"2026-03-12T19:10:47.446569Z","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-12T19:10:47.446926Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-12T19:10:47.447023Z","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-12T19:10:47.447122Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-03-12T19:10:47.447176Z","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-12T19:10:47.448216Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-03-12T19:10:47.449238Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-12T19:10:47.527187Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"77.576279ms"} {"level":"info","ts":"2026-03-12T19:10:47.670599Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-03-12T19:10:47.670713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-03-12T19:10:47.670744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-03-12T19:10:47.670762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-12T19:10:47.670783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-03-12T19:10:47.670832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-03-12T19:10:47.793847Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-12T19:10:47.795238Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-12T19:10:47.817328Z","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-12T19:10:47.81967Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-12T19:10:47.825329Z","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-12T19:10:47.825552Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-12T19:10:47.825599Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-12T19:10:47.825843Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-03-12T19:10:47.825871Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-03-12T19:10:47.82595Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-12T19:10:47.826188Z","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-12T19:10:47.826286Z","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-12T19:10:47.826297Z","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-12T19:10:47.844422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-03-12T19:10:47.844515Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-03-12T19:10:48.47204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-03-12T19:10:48.472208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-12T19:10:48.472298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-03-12T19:10:48.472367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-03-12T19:10:48.472433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-03-12T19:10:48.472489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-03-12T19:10:48.472532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-03-12T19:10:48.475702Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-03-12T19:10:48.475723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-12T19:10:48.475824Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-12T19:10:48.476693Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-12T19:10:48.476791Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-12T19:10:48.475826Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:10:48.478655Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-12T19:10:48.484015Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:10:48.484184Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:10:48.484292Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:10:48.485423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"warn","ts":"2026-03-12T19:10:50.564152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.027213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-12T19:10:50.564265Z","caller":"traceutil/trace.go:171","msg":"trace[1166942046] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:7; }","duration":"670.409418ms","start":"2026-03-12T19:10:49.89384Z","end":"2026-03-12T19:10:50.56425Z","steps":["trace[1166942046] 'read index received' (duration: 203.179425ms)","trace[1166942046] 'applied index is now lower than readState.Index' (duration: 467.228803ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:50.564452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.591564ms","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-12T19:10:50.564325Z","caller":"traceutil/trace.go:171","msg":"trace[895125370] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"671.066001ms","start":"2026-03-12T19:10:49.893219Z","end":"2026-03-12T19:10:50.564285Z","steps":["trace[895125370] 'process raft request' (duration: 203.76776ms)","trace[895125370] 'compare' (duration: 465.878307ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:50.564506Z","caller":"traceutil/trace.go:171","msg":"trace[1172947659] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:5; }","duration":"670.661162ms","start":"2026-03-12T19:10:49.893826Z","end":"2026-03-12T19:10:50.564488Z","steps":["trace[1172947659] 'agreement among raft nodes before linearized reading' (duration: 670.532736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.564542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.893819Z","time spent":"670.71471ms","remote":"127.0.0.1:35772","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-12T19:10:50.564616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.893196Z","time spent":"671.331415ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:10:50.593566Z","caller":"traceutil/trace.go:171","msg":"trace[890531846] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"683.589093ms","start":"2026-03-12T19:10:49.909956Z","end":"2026-03-12T19:10:50.593545Z","steps":["trace[890531846] 'process raft request' (duration: 683.266031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.593664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.909943Z","time spent":"683.677181ms","remote":"127.0.0.1:36112","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-12T19:10:50.593958Z","caller":"traceutil/trace.go:171","msg":"trace[909026121] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"681.036267ms","start":"2026-03-12T19:10:49.912875Z","end":"2026-03-12T19:10:50.593911Z","steps":["trace[909026121] 'process raft request' (duration: 680.781243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.594005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.996833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:10:50.594036Z","caller":"traceutil/trace.go:171","msg":"trace[1365473391] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"682.877631ms","start":"2026-03-12T19:10:49.911093Z","end":"2026-03-12T19:10:50.59397Z","steps":["trace[1365473391] 'process raft request' (duration: 682.511061ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:50.5941Z","caller":"traceutil/trace.go:171","msg":"trace[2097302767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"700.147449ms","start":"2026-03-12T19:10:49.893918Z","end":"2026-03-12T19:10:50.594065Z","steps":["trace[2097302767] 'agreement among raft nodes before linearized reading' (duration: 699.778838ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:50.594119Z","caller":"traceutil/trace.go:171","msg":"trace[557143302] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"683.065866ms","start":"2026-03-12T19:10:49.91104Z","end":"2026-03-12T19:10:50.594106Z","steps":["trace[557143302] 'process raft request' (duration: 682.535479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.594127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.911089Z","time spent":"682.993879ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:10:50.59418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.911032Z","time spent":"683.121055ms","remote":"127.0.0.1:36214","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-12T19:10:50.594245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.893907Z","time spent":"700.317805ms","remote":"127.0.0.1:35636","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-12T19:10:50.594309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.427727ms","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-12T19:10:50.594348Z","caller":"traceutil/trace.go:171","msg":"trace[1439175322] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"683.474546ms","start":"2026-03-12T19:10:49.910863Z","end":"2026-03-12T19:10:50.594338Z","steps":["trace[1439175322] 'agreement among raft nodes before linearized reading' (duration: 683.403508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.594355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.119147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-12T19:10:50.594368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.910858Z","time spent":"683.505386ms","remote":"127.0.0.1:35772","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":"info","ts":"2026-03-12T19:10:50.594385Z","caller":"traceutil/trace.go:171","msg":"trace[59278726] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:11; }","duration":"506.193235ms","start":"2026-03-12T19:10:50.088181Z","end":"2026-03-12T19:10:50.594374Z","steps":["trace[59278726] 'agreement among raft nodes before linearized reading' (duration: 506.154656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.594422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.088159Z","time spent":"506.256563ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-03-12T19:10:50.594505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.622092ms","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-12T19:10:50.594503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.07296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:10:50.594534Z","caller":"traceutil/trace.go:171","msg":"trace[1683161641] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"683.659212ms","start":"2026-03-12T19:10:49.910868Z","end":"2026-03-12T19:10:50.594527Z","steps":["trace[1683161641] 'agreement among raft nodes before linearized reading' (duration: 683.620403ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:50.594543Z","caller":"traceutil/trace.go:171","msg":"trace[892283224] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"630.146968ms","start":"2026-03-12T19:10:49.964385Z","end":"2026-03-12T19:10:50.594532Z","steps":["trace[892283224] 'agreement among raft nodes before linearized reading' (duration: 630.08803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.594558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.163363ms","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-12T19:10:50.594584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.96436Z","time spent":"630.217176ms","remote":"127.0.0.1:35772","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-12T19:10:50.594592Z","caller":"traceutil/trace.go:171","msg":"trace[1361634907] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"649.227641ms","start":"2026-03-12T19:10:49.945351Z","end":"2026-03-12T19:10:50.594578Z","steps":["trace[1361634907] 'agreement among raft nodes before linearized reading' (duration: 649.187302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.5946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.73264ms","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-12T19:10:50.594675Z","caller":"traceutil/trace.go:171","msg":"trace[1429584626] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"683.814507ms","start":"2026-03-12T19:10:49.910842Z","end":"2026-03-12T19:10:50.594656Z","steps":["trace[1429584626] 'agreement among raft nodes before linearized reading' (duration: 683.72079ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:50.594753Z","caller":"traceutil/trace.go:171","msg":"trace[1900785513] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"684.744624ms","start":"2026-03-12T19:10:49.909997Z","end":"2026-03-12T19:10:50.594741Z","steps":["trace[1900785513] 'process raft request' (duration: 683.415897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.594768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.910836Z","time spent":"683.923615ms","remote":"127.0.0.1:35772","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-12T19:10:50.594313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.805112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-12T19:10:50.594809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.909989Z","time spent":"684.792963ms","remote":"127.0.0.1:36214","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-12T19:10:50.594836Z","caller":"traceutil/trace.go:171","msg":"trace[1423350349] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"673.353399ms","start":"2026-03-12T19:10:49.921473Z","end":"2026-03-12T19:10:50.594827Z","steps":["trace[1423350349] 'agreement among raft nodes before linearized reading' (duration: 672.787283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.594618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.945313Z","time spent":"649.300099ms","remote":"127.0.0.1:35772","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-12T19:10:50.594868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.921462Z","time spent":"673.400017ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-12T19:10:50.594106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.912868Z","time spent":"681.178834ms","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:10:50.594554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.910863Z","time spent":"683.686351ms","remote":"127.0.0.1:35736","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":"info","ts":"2026-03-12T19:10:50.595234Z","caller":"traceutil/trace.go:171","msg":"trace[910825440] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"684.969469ms","start":"2026-03-12T19:10:49.910252Z","end":"2026-03-12T19:10:50.595221Z","steps":["trace[910825440] 'process raft request' (duration: 683.266842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:50.595325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:49.910235Z","time spent":"685.047487ms","remote":"127.0.0.1:36214","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-12T19:10:51.099343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537141340436361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-12T19:10:51.398731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.717229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-12T19:10:51.398886Z","caller":"traceutil/trace.go:171","msg":"trace[696781848] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"830.546745ms","start":"2026-03-12T19:10:50.568321Z","end":"2026-03-12T19:10:51.398867Z","steps":["trace[696781848] 'process raft request' (duration: 640.606171ms)","trace[696781848] 'compare' (duration: 189.540434ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:51.399076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.568292Z","time spent":"830.75336ms","remote":"127.0.0.1:36214","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-12T19:10:51.446732Z","caller":"traceutil/trace.go:171","msg":"trace[1474020565] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"848.423337ms","start":"2026-03-12T19:10:50.598283Z","end":"2026-03-12T19:10:51.446706Z","steps":["trace[1474020565] 'process raft request' (duration: 848.266931ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:51.446806Z","caller":"traceutil/trace.go:171","msg":"trace[66829773] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"848.179353ms","start":"2026-03-12T19:10:50.598599Z","end":"2026-03-12T19:10:51.446778Z","steps":["trace[66829773] 'process raft request' (duration: 848.042407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.446834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.598266Z","time spent":"848.512044ms","remote":"127.0.0.1:36112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:10:51.44694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.598581Z","time spent":"848.29078ms","remote":"127.0.0.1:35772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:10:51.446984Z","caller":"traceutil/trace.go:171","msg":"trace[451416116] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"848.178823ms","start":"2026-03-12T19:10:50.598764Z","end":"2026-03-12T19:10:51.446943Z","steps":["trace[451416116] 'process raft request' (duration: 847.91425ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:51.447084Z","caller":"traceutil/trace.go:171","msg":"trace[1316215626] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"846.866036ms","start":"2026-03-12T19:10:50.600207Z","end":"2026-03-12T19:10:51.447073Z","steps":["trace[1316215626] 'process raft request' (duration: 846.542914ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:51.447103Z","caller":"traceutil/trace.go:171","msg":"trace[355106120] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"848.098425ms","start":"2026-03-12T19:10:50.598941Z","end":"2026-03-12T19:10:51.44704Z","steps":["trace[355106120] 'process raft request' (duration: 847.783283ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:51.447249Z","caller":"traceutil/trace.go:171","msg":"trace[1553863363] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"846.223102ms","start":"2026-03-12T19:10:50.600948Z","end":"2026-03-12T19:10:51.447171Z","steps":["trace[1553863363] 'process raft request' (duration: 845.88235ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:51.447286Z","caller":"traceutil/trace.go:171","msg":"trace[1731702162] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:14; }","duration":"848.800357ms","start":"2026-03-12T19:10:50.598464Z","end":"2026-03-12T19:10:51.447264Z","steps":["trace[1731702162] 'read index received' (duration: 610.553078ms)","trace[1731702162] 'applied index is now lower than readState.Index' (duration: 238.245279ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:51.447351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.600937Z","time spent":"846.365298ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:10:51.447376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.598918Z","time spent":"848.32105ms","remote":"127.0.0.1:35772","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-12T19:10:51.447475Z","caller":"traceutil/trace.go:171","msg":"trace[2000185370] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"840.002567ms","start":"2026-03-12T19:10:50.607454Z","end":"2026-03-12T19:10:51.447456Z","steps":["trace[2000185370] 'process raft request' (duration: 839.614176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.447163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.598747Z","time spent":"848.329749ms","remote":"127.0.0.1:36214","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-12T19:10:51.44784Z","caller":"traceutil/trace.go:171","msg":"trace[1401381190] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"846.128724ms","start":"2026-03-12T19:10:50.601685Z","end":"2026-03-12T19:10:51.447813Z","steps":["trace[1401381190] 'process raft request' (duration: 845.285365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.447923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.601676Z","time spent":"846.208472ms","remote":"127.0.0.1:36214","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-12T19:10:51.447978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.471921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-03-12T19:10:51.448074Z","caller":"traceutil/trace.go:171","msg":"trace[1706909348] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"849.616678ms","start":"2026-03-12T19:10:50.598442Z","end":"2026-03-12T19:10:51.448059Z","steps":["trace[1706909348] 'agreement among raft nodes before linearized reading' (duration: 849.374544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.448153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.598435Z","time spent":"849.706466ms","remote":"127.0.0.1:35820","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-12T19:10:51.44922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.607443Z","time spent":"840.125864ms","remote":"127.0.0.1:35820","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-12T19:10:51.449205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.60019Z","time spent":"846.929274ms","remote":"127.0.0.1:36214","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-12T19:10:51.463294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.56404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-12T19:10:51.463299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.786226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-03-12T19:10:51.463309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.791439ms","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-12T19:10:51.463356Z","caller":"traceutil/trace.go:171","msg":"trace[1937351050] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"183.859605ms","start":"2026-03-12T19:10:51.279484Z","end":"2026-03-12T19:10:51.463343Z","steps":["trace[1937351050] 'agreement among raft nodes before linearized reading' (duration: 183.743937ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:51.463356Z","caller":"traceutil/trace.go:171","msg":"trace[939507657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"563.666928ms","start":"2026-03-12T19:10:50.899668Z","end":"2026-03-12T19:10:51.463335Z","steps":["trace[939507657] 'agreement among raft nodes before linearized reading' (duration: 563.536681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.463398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.899603Z","time spent":"563.790064ms","remote":"127.0.0.1:35634","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-12T19:10:51.463405Z","caller":"traceutil/trace.go:171","msg":"trace[683809498] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:20; }","duration":"668.034563ms","start":"2026-03-12T19:10:50.795346Z","end":"2026-03-12T19:10:51.463381Z","steps":["trace[683809498] 'agreement among raft nodes before linearized reading' (duration: 667.796339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.463441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.51275ms","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-12T19:10:51.463494Z","caller":"traceutil/trace.go:171","msg":"trace[1087809747] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:20; }","duration":"664.599028ms","start":"2026-03-12T19:10:50.798878Z","end":"2026-03-12T19:10:51.463477Z","steps":["trace[1087809747] 'agreement among raft nodes before linearized reading' (duration: 664.53138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.463504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.795323Z","time spent":"668.15861ms","remote":"127.0.0.1:36000","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-12T19:10:51.463529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.798856Z","time spent":"664.667127ms","remote":"127.0.0.1:36014","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":"warn","ts":"2026-03-12T19:10:51.463513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.788854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-03-12T19:10:51.463299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.341546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-12T19:10:51.463676Z","caller":"traceutil/trace.go:171","msg":"trace[145883865] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"814.767296ms","start":"2026-03-12T19:10:50.648901Z","end":"2026-03-12T19:10:51.463668Z","steps":["trace[145883865] 'agreement among raft nodes before linearized reading' (duration: 814.342926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.463701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.648886Z","time spent":"814.809565ms","remote":"127.0.0.1:35772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-12T19:10:51.463709Z","caller":"traceutil/trace.go:171","msg":"trace[294808988] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"806.991069ms","start":"2026-03-12T19:10:50.656658Z","end":"2026-03-12T19:10:51.463649Z","steps":["trace[294808988] 'agreement among raft nodes before linearized reading' (duration: 806.639998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.463816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.656645Z","time spent":"807.157644ms","remote":"127.0.0.1:35772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-12T19:10:51.464424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.255993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:10:51.46451Z","caller":"traceutil/trace.go:171","msg":"trace[77522694] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"813.40988ms","start":"2026-03-12T19:10:50.651066Z","end":"2026-03-12T19:10:51.464476Z","steps":["trace[77522694] 'agreement among raft nodes before linearized reading' (duration: 812.090582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:51.46465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:50.651053Z","time spent":"813.536466ms","remote":"127.0.0.1:35772","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-12T19:10:51.74015Z","caller":"traceutil/trace.go:171","msg":"trace[1719346163] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"110.00267ms","start":"2026-03-12T19:10:51.630119Z","end":"2026-03-12T19:10:51.740121Z","steps":["trace[1719346163] 'process raft request' (duration: 45.359709ms)","trace[1719346163] 'compare' (duration: 64.512854ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:51.924907Z","caller":"traceutil/trace.go:171","msg":"trace[12886351] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"103.700975ms","start":"2026-03-12T19:10:51.821157Z","end":"2026-03-12T19:10:51.924858Z","steps":["trace[12886351] 'process raft request' (duration: 61.006442ms)","trace[12886351] 'compare' (duration: 42.39625ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:51.925268Z","caller":"traceutil/trace.go:171","msg":"trace[1137999349] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"103.447111ms","start":"2026-03-12T19:10:51.821785Z","end":"2026-03-12T19:10:51.925232Z","steps":["trace[1137999349] 'process raft request' (duration: 103.004272ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:51.925798Z","caller":"traceutil/trace.go:171","msg":"trace[359903630] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"101.533228ms","start":"2026-03-12T19:10:51.824246Z","end":"2026-03-12T19:10:51.925779Z","steps":["trace[359903630] 'process raft request' (duration: 100.862425ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:53.723476Z","caller":"traceutil/trace.go:171","msg":"trace[492436391] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"114.113787ms","start":"2026-03-12T19:10:53.609334Z","end":"2026-03-12T19:10:53.723448Z","steps":["trace[492436391] 'process raft request' (duration: 90.321909ms)","trace[492436391] 'compare' (duration: 23.604972ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:54.073735Z","caller":"traceutil/trace.go:171","msg":"trace[624804002] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"179.932333ms","start":"2026-03-12T19:10:53.893775Z","end":"2026-03-12T19:10:54.073707Z","steps":["trace[624804002] 'process raft request' (duration: 84.108441ms)","trace[624804002] 'compare' (duration: 95.689976ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:54.260211Z","caller":"traceutil/trace.go:171","msg":"trace[1462165697] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"115.936965ms","start":"2026-03-12T19:10:54.144244Z","end":"2026-03-12T19:10:54.260181Z","steps":["trace[1462165697] 'process raft request' (duration: 36.314545ms)","trace[1462165697] 'compare' (duration: 79.498063ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:54.798481Z","caller":"traceutil/trace.go:171","msg":"trace[329851337] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"130.041772ms","start":"2026-03-12T19:10:54.668396Z","end":"2026-03-12T19:10:54.798438Z","steps":["trace[329851337] 'process raft request' (duration: 35.70702ms)","trace[329851337] 'compare' (duration: 94.173666ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:55.030994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.540812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:10:55.031142Z","caller":"traceutil/trace.go:171","msg":"trace[824204505] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"171.311638ms","start":"2026-03-12T19:10:54.859808Z","end":"2026-03-12T19:10:55.03112Z","steps":["trace[824204505] 'process raft request' (duration: 40.506612ms)","trace[824204505] 'compare' (duration: 130.372956ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:55.270525Z","caller":"traceutil/trace.go:171","msg":"trace[1390128997] linearizableReadLoop","detail":"{readStateIndex:194; appliedIndex:193; }","duration":"171.944711ms","start":"2026-03-12T19:10:55.098563Z","end":"2026-03-12T19:10:55.270508Z","steps":["trace[1390128997] 'read index received' (duration: 157.358835ms)","trace[1390128997] 'applied index is now lower than readState.Index' (duration: 14.585166ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:55.270668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.063988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:55.2707Z","caller":"traceutil/trace.go:171","msg":"trace[1217467313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:189; }","duration":"172.144106ms","start":"2026-03-12T19:10:55.098546Z","end":"2026-03-12T19:10:55.27069Z","steps":["trace[1217467313] 'agreement among raft nodes before linearized reading' (duration: 172.030849ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:55.270701Z","caller":"traceutil/trace.go:171","msg":"trace[291276121] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"177.066057ms","start":"2026-03-12T19:10:55.093608Z","end":"2026-03-12T19:10:55.270674Z","steps":["trace[291276121] 'process raft request' (duration: 162.300375ms)","trace[291276121] 'compare' (duration: 14.523448ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:55.480314Z","caller":"traceutil/trace.go:171","msg":"trace[1780359240] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"118.649404ms","start":"2026-03-12T19:10:55.361631Z","end":"2026-03-12T19:10:55.480281Z","steps":["trace[1780359240] 'process raft request' (duration: 32.110822ms)","trace[1780359240] 'compare' (duration: 86.282198ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:55.480357Z","caller":"traceutil/trace.go:171","msg":"trace[26534529] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"116.634633ms","start":"2026-03-12T19:10:55.363698Z","end":"2026-03-12T19:10:55.480332Z","steps":["trace[26534529] 'process raft request' (duration: 116.456507ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:55.675396Z","caller":"traceutil/trace.go:171","msg":"trace[493139621] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"123.847646ms","start":"2026-03-12T19:10:55.551519Z","end":"2026-03-12T19:10:55.675367Z","steps":["trace[493139621] 'process raft request' (duration: 87.907648ms)","trace[493139621] 'compare' (duration: 35.830911ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:55.676065Z","caller":"traceutil/trace.go:171","msg":"trace[1915197576] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"101.099549ms","start":"2026-03-12T19:10:55.574948Z","end":"2026-03-12T19:10:55.676048Z","steps":["trace[1915197576] 'process raft request' (duration: 100.863055ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:56.47667Z","caller":"traceutil/trace.go:171","msg":"trace[1211129854] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"191.881088ms","start":"2026-03-12T19:10:56.284763Z","end":"2026-03-12T19:10:56.476644Z","steps":["trace[1211129854] 'process raft request' (duration: 131.72339ms)","trace[1211129854] 'compare' (duration: 60.002202ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:58.123215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.544994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:10:58.123371Z","caller":"traceutil/trace.go:171","msg":"trace[884738977] linearizableReadLoop","detail":"{readStateIndex:227; appliedIndex:226; }","duration":"1.42366748s","start":"2026-03-12T19:10:56.69968Z","end":"2026-03-12T19:10:58.123348Z","steps":["trace[884738977] 'read index received' (duration: 475.737035ms)","trace[884738977] 'applied index is now lower than readState.Index' (duration: 947.928835ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:58.123571Z","caller":"traceutil/trace.go:171","msg":"trace[637552816] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"1.640631503s","start":"2026-03-12T19:10:56.482874Z","end":"2026-03-12T19:10:58.123506Z","steps":["trace[637552816] 'process raft request' (duration: 692.684178ms)","trace[637552816] 'compare' (duration: 946.06031ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:58.123754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.084175ms","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-12T19:10:58.123807Z","caller":"traceutil/trace.go:171","msg":"trace[1595992372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:222; }","duration":"639.153634ms","start":"2026-03-12T19:10:57.484636Z","end":"2026-03-12T19:10:58.12379Z","steps":["trace[1595992372] 'agreement among raft nodes before linearized reading' (duration: 639.066936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:58.123775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284320634s","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-12T19:10:58.12378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.254492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:58.123885Z","caller":"traceutil/trace.go:171","msg":"trace[736524104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:222; }","duration":"1.284513149s","start":"2026-03-12T19:10:56.839345Z","end":"2026-03-12T19:10:58.123858Z","steps":["trace[736524104] 'agreement among raft nodes before linearized reading' (duration: 1.284258535s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:58.123866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:57.484619Z","time spent":"639.235041ms","remote":"127.0.0.1:35910","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-12T19:10:58.124107Z","caller":"traceutil/trace.go:171","msg":"trace[5895042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:222; }","duration":"599.434779ms","start":"2026-03-12T19:10:57.524455Z","end":"2026-03-12T19:10:58.12389Z","steps":["trace[5895042] 'agreement among raft nodes before linearized reading' (duration: 599.196334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:58.124132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:56.839314Z","time spent":"1.284806622s","remote":"127.0.0.1:35910","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-12T19:10:58.123655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423926884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:10:58.124184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:57.524444Z","time spent":"599.726131ms","remote":"127.0.0.1:35634","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-12T19:10:58.124298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:56.48285Z","time spent":"1.640910806s","remote":"127.0.0.1:35988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":656,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:10:58.124471Z","caller":"traceutil/trace.go:171","msg":"trace[332265574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:222; }","duration":"1.424653346s","start":"2026-03-12T19:10:56.69958Z","end":"2026-03-12T19:10:58.124233Z","steps":["trace[332265574] 'agreement among raft nodes before linearized reading' (duration: 1.423929664s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:58.124751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:56.699556Z","time spent":"1.425043217s","remote":"127.0.0.1:35636","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-12T19:10:58.658952Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537141340436734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-12T19:11:00.009373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.560835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:11:00.009821Z","caller":"traceutil/trace.go:171","msg":"trace[1534044484] linearizableReadLoop","detail":"{readStateIndex:229; appliedIndex:227; }","duration":"1.851254915s","start":"2026-03-12T19:10:58.158547Z","end":"2026-03-12T19:11:00.009802Z","steps":["trace[1534044484] 'read index received' (duration: 866.236671ms)","trace[1534044484] 'applied index is now lower than readState.Index' (duration: 985.017415ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:00.011167Z","caller":"traceutil/trace.go:171","msg":"trace[1108144553] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"1.853271757s","start":"2026-03-12T19:10:58.157837Z","end":"2026-03-12T19:11:00.011109Z","steps":["trace[1108144553] 'process raft request' (duration: 866.877296ms)","trace[1108144553] 'compare' (duration: 983.736325ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:00.011246Z","caller":"traceutil/trace.go:171","msg":"trace[1736622990] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"1.852537175s","start":"2026-03-12T19:10:58.158663Z","end":"2026-03-12T19:11:00.0112Z","steps":["trace[1736622990] 'process raft request' (duration: 1.85107751s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:00.011454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:58.157813Z","time spent":"1.853482403s","remote":"127.0.0.1:35910","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-12T19:11:00.011489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:58.158647Z","time spent":"1.852706132s","remote":"127.0.0.1:35910","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-12T19:11:00.011802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.853247528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-12T19:11:00.011853Z","caller":"traceutil/trace.go:171","msg":"trace[2022092812] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:224; }","duration":"1.853327946s","start":"2026-03-12T19:10:58.158509Z","end":"2026-03-12T19:11:00.011837Z","steps":["trace[2022092812] 'agreement among raft nodes before linearized reading' (duration: 1.853202909s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:00.011897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:58.15849Z","time spent":"1.853398935s","remote":"127.0.0.1:35772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-12T19:11:00.01508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709198854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:11:00.015137Z","caller":"traceutil/trace.go:171","msg":"trace[1299980326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:225; }","duration":"1.709294201s","start":"2026-03-12T19:10:58.305825Z","end":"2026-03-12T19:11:00.01512Z","steps":["trace[1299980326] 'agreement among raft nodes before linearized reading' (duration: 1.709162834s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:00.015127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489321605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:11:00.015177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:58.305803Z","time spent":"1.70936769s","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-12T19:11:00.015145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.815183388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:11:00.015219Z","caller":"traceutil/trace.go:171","msg":"trace[914387313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:225; }","duration":"1.489475931s","start":"2026-03-12T19:10:58.525722Z","end":"2026-03-12T19:11:00.015198Z","steps":["trace[914387313] 'agreement among raft nodes before linearized reading' (duration: 1.489253867s)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:00.015227Z","caller":"traceutil/trace.go:171","msg":"trace[707638404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:225; }","duration":"1.815318505s","start":"2026-03-12T19:10:58.199893Z","end":"2026-03-12T19:11:00.015211Z","steps":["trace[707638404] 'agreement among raft nodes before linearized reading' (duration: 1.815192978s)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:00.015103Z","caller":"traceutil/trace.go:171","msg":"trace[1226560714] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"967.118171ms","start":"2026-03-12T19:10:59.047952Z","end":"2026-03-12T19:11:00.01507Z","steps":["trace[1226560714] 'process raft request' (duration: 966.890807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:00.015277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:58.525698Z","time spent":"1.489566779s","remote":"127.0.0.1:35634","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-12T19:11:00.015278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:58.199867Z","time spent":"1.815397303s","remote":"127.0.0.1:35636","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-12T19:11:00.016139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:59.047904Z","time spent":"967.426594ms","remote":"127.0.0.1:35726","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-12T19:11:06.201609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.668594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:11:06.20176Z","caller":"traceutil/trace.go:171","msg":"trace[480788534] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"261.87075ms","start":"2026-03-12T19:11:05.939865Z","end":"2026-03-12T19:11:06.201735Z","steps":["trace[480788534] 'process raft request' (duration: 116.954791ms)","trace[480788534] 'compare' (duration: 144.500438ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:06.521032Z","caller":"traceutil/trace.go:171","msg":"trace[665401410] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:378; }","duration":"573.644988ms","start":"2026-03-12T19:11:05.947351Z","end":"2026-03-12T19:11:06.520995Z","steps":["trace[665401410] 'read index received' (duration: 109.424347ms)","trace[665401410] 'applied index is now lower than readState.Index' (duration: 464.219341ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:06.521215Z","caller":"traceutil/trace.go:171","msg":"trace[598514247] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"581.29266ms","start":"2026-03-12T19:11:05.939868Z","end":"2026-03-12T19:11:06.521161Z","steps":["trace[598514247] 'process raft request' (duration: 540.598904ms)","trace[598514247] 'compare' (duration: 40.403533ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:06.521247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.853561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-12T19:11:06.521343Z","caller":"traceutil/trace.go:171","msg":"trace[1026133057] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:367; }","duration":"523.010287ms","start":"2026-03-12T19:11:05.998318Z","end":"2026-03-12T19:11:06.521328Z","steps":["trace[1026133057] 'agreement among raft nodes before linearized reading' (duration: 522.861071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.521364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.972831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-03-12T19:11:06.521427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:05.998293Z","time spent":"523.121814ms","remote":"127.0.0.1:35854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-03-12T19:11:06.521471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:05.939849Z","time spent":"581.483126ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:11:06.521489Z","caller":"traceutil/trace.go:171","msg":"trace[1512816282] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:367; }","duration":"574.164826ms","start":"2026-03-12T19:11:05.947289Z","end":"2026-03-12T19:11:06.521454Z","steps":["trace[1512816282] 'agreement among raft nodes before linearized reading' (duration: 573.858864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.521525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.624813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-03-12T19:11:06.521552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:05.947268Z","time spent":"574.275743ms","remote":"127.0.0.1:35854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-03-12T19:11:06.521561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.145194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-12T19:11:06.521617Z","caller":"traceutil/trace.go:171","msg":"trace[575820252] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:367; }","duration":"423.688432ms","start":"2026-03-12T19:11:06.097877Z","end":"2026-03-12T19:11:06.521566Z","steps":["trace[575820252] 'agreement among raft nodes before linearized reading' (duration: 423.613474ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:06.521634Z","caller":"traceutil/trace.go:171","msg":"trace[1557956392] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:367; }","duration":"273.264901ms","start":"2026-03-12T19:11:06.248344Z","end":"2026-03-12T19:11:06.521609Z","steps":["trace[1557956392] 'agreement among raft nodes before linearized reading' (duration: 273.167773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.521668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.097869Z","time spent":"423.792ms","remote":"127.0.0.1:35854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-12T19:11:06.521432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.544912ms","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-12T19:11:06.52171Z","caller":"traceutil/trace.go:171","msg":"trace[190202183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"474.868645ms","start":"2026-03-12T19:11:06.046833Z","end":"2026-03-12T19:11:06.521702Z","steps":["trace[190202183] 'agreement among raft nodes before linearized reading' (duration: 474.458574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.521743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.046826Z","time spent":"474.908963ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-12T19:11:06.521745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.984823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-12T19:11:06.521419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.513986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-12T19:11:06.521826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.570584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-12T19:11:06.521876Z","caller":"traceutil/trace.go:171","msg":"trace[223584275] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:367; }","duration":"473.656892ms","start":"2026-03-12T19:11:06.048209Z","end":"2026-03-12T19:11:06.521866Z","steps":["trace[223584275] 'agreement among raft nodes before linearized reading' (duration: 473.589073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.521906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.048184Z","time spent":"473.715421ms","remote":"127.0.0.1:35854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-03-12T19:11:06.521914Z","caller":"traceutil/trace.go:171","msg":"trace[972703825] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:367; }","duration":"374.007645ms","start":"2026-03-12T19:11:06.147821Z","end":"2026-03-12T19:11:06.521828Z","steps":["trace[972703825] 'agreement among raft nodes before linearized reading' (duration: 373.420038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.521948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.061207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-12T19:11:06.521998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.147806Z","time spent":"374.150261ms","remote":"127.0.0.1:35854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-03-12T19:11:06.522013Z","caller":"traceutil/trace.go:171","msg":"trace[1301476404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"482.126616ms","start":"2026-03-12T19:11:06.039868Z","end":"2026-03-12T19:11:06.521994Z","steps":["trace[1301476404] 'agreement among raft nodes before linearized reading' (duration: 481.95087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.522048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.097346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-12T19:11:06.521795Z","caller":"traceutil/trace.go:171","msg":"trace[1052668498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"484.040702ms","start":"2026-03-12T19:11:06.03774Z","end":"2026-03-12T19:11:06.52178Z","steps":["trace[1052668498] 'agreement among raft nodes before linearized reading' (duration: 483.925914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.522054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.0398Z","time spent":"482.244314ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-12T19:11:06.522076Z","caller":"traceutil/trace.go:171","msg":"trace[1209875922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:367; }","duration":"326.145164ms","start":"2026-03-12T19:11:06.195924Z","end":"2026-03-12T19:11:06.522069Z","steps":["trace[1209875922] 'agreement among raft nodes before linearized reading' (duration: 326.092885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.522091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.037734Z","time spent":"484.346464ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-12T19:11:06.522102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.195879Z","time spent":"326.217572ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-12T19:11:06.52218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.910337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-12T19:11:06.522202Z","caller":"traceutil/trace.go:171","msg":"trace[1424651725] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:367; }","duration":"323.974605ms","start":"2026-03-12T19:11:06.198221Z","end":"2026-03-12T19:11:06.522195Z","steps":["trace[1424651725] 'agreement among raft nodes before linearized reading' (duration: 323.932896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.52222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.198188Z","time spent":"324.027964ms","remote":"127.0.0.1:35854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-03-12T19:11:06.527037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.320679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-12T19:11:06.527116Z","caller":"traceutil/trace.go:171","msg":"trace[2055489958] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:367; }","duration":"320.457396ms","start":"2026-03-12T19:11:06.206645Z","end":"2026-03-12T19:11:06.527103Z","steps":["trace[2055489958] 'agreement among raft nodes before linearized reading' (duration: 315.506621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:06.527159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.206619Z","time spent":"320.530234ms","remote":"127.0.0.1:35854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-03-12T19:11:06.745505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.599532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:11:06.745742Z","caller":"traceutil/trace.go:171","msg":"trace[1651981158] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"159.336694ms","start":"2026-03-12T19:11:06.586322Z","end":"2026-03-12T19:11:06.745659Z","steps":["trace[1651981158] 'process raft request' (duration: 35.507407ms)","trace[1651981158] 'compare' (duration: 122.898318ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:06.745861Z","caller":"traceutil/trace.go:171","msg":"trace[1629163481] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"158.896743ms","start":"2026-03-12T19:11:06.586915Z","end":"2026-03-12T19:11:06.745812Z","steps":["trace[1629163481] 'read index received' (duration: 34.866161ms)","trace[1629163481] 'applied index is now lower than readState.Index' (duration: 124.025422ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:06.746206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.186067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-12T19:11:06.746318Z","caller":"traceutil/trace.go:171","msg":"trace[891141418] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:380; }","duration":"159.416182ms","start":"2026-03-12T19:11:06.586878Z","end":"2026-03-12T19:11:06.746294Z","steps":["trace[891141418] 'agreement among raft nodes before linearized reading' (duration: 159.06565ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:06.7465Z","caller":"traceutil/trace.go:171","msg":"trace[1132570585] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"158.20927ms","start":"2026-03-12T19:11:06.588265Z","end":"2026-03-12T19:11:06.746474Z","steps":["trace[1132570585] 'process raft request' (duration: 157.368719ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:06.747049Z","caller":"traceutil/trace.go:171","msg":"trace[1357688947] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"157.946956ms","start":"2026-03-12T19:11:06.589079Z","end":"2026-03-12T19:11:06.747026Z","steps":["trace[1357688947] 'process raft request' (duration: 157.242992ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:06.747058Z","caller":"traceutil/trace.go:171","msg":"trace[742260475] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"155.781276ms","start":"2026-03-12T19:11:06.591253Z","end":"2026-03-12T19:11:06.747034Z","steps":["trace[742260475] 'process raft request' (duration: 155.715057ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:06.922052Z","caller":"traceutil/trace.go:171","msg":"trace[1347570869] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"169.118537ms","start":"2026-03-12T19:11:06.7529Z","end":"2026-03-12T19:11:06.922018Z","steps":["trace[1347570869] 'process raft request' (duration: 120.439046ms)","trace[1347570869] 'compare' (duration: 48.522764ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:07.119751Z","caller":"traceutil/trace.go:171","msg":"trace[1668116434] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"179.960063ms","start":"2026-03-12T19:11:06.939765Z","end":"2026-03-12T19:11:07.119725Z","steps":["trace[1668116434] 'process raft request' (duration: 179.912345ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:07.119866Z","caller":"traceutil/trace.go:171","msg":"trace[1408433395] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"360.256941ms","start":"2026-03-12T19:11:06.759583Z","end":"2026-03-12T19:11:07.11984Z","steps":["trace[1408433395] 'process raft request' (duration: 360.001837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:07.119989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.759577Z","time spent":"360.347159ms","remote":"127.0.0.1:35834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:11:07.120192Z","caller":"traceutil/trace.go:171","msg":"trace[1922872163] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"366.736361ms","start":"2026-03-12T19:11:06.753442Z","end":"2026-03-12T19:11:07.120179Z","steps":["trace[1922872163] 'process raft request' (duration: 274.22884ms)","trace[1922872163] 'compare' (duration: 91.746939ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:07.120247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.753406Z","time spent":"366.819779ms","remote":"127.0.0.1:35756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:11:07.120261Z","caller":"traceutil/trace.go:171","msg":"trace[541962353] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:396; }","duration":"364.697048ms","start":"2026-03-12T19:11:06.75555Z","end":"2026-03-12T19:11:07.120247Z","steps":["trace[541962353] 'read index received' (duration: 117.805777ms)","trace[541962353] 'applied index is now lower than readState.Index' (duration: 246.889541ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:07.120701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.142747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-03-12T19:11:07.120747Z","caller":"traceutil/trace.go:171","msg":"trace[878961450] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:387; }","duration":"365.212396ms","start":"2026-03-12T19:11:06.755523Z","end":"2026-03-12T19:11:07.120735Z","steps":["trace[878961450] 'agreement among raft nodes before linearized reading' (duration: 365.093189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:07.120781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:06.755515Z","time spent":"365.256185ms","remote":"127.0.0.1:36118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3730,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-03-12T19:11:07.458384Z","caller":"traceutil/trace.go:171","msg":"trace[1549311356] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"126.198872ms","start":"2026-03-12T19:11:07.332144Z","end":"2026-03-12T19:11:07.458342Z","steps":["trace[1549311356] 'read index received' (duration: 45.474359ms)","trace[1549311356] 'applied index is now lower than readState.Index' (duration: 80.722813ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:07.458658Z","caller":"traceutil/trace.go:171","msg":"trace[1714452868] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"128.720124ms","start":"2026-03-12T19:11:07.329758Z","end":"2026-03-12T19:11:07.458478Z","steps":["trace[1714452868] 'process raft request' (duration: 47.829564ms)","trace[1714452868] 'compare' (duration: 80.575687ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:07.45862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.436327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-03-12T19:11:07.45911Z","caller":"traceutil/trace.go:171","msg":"trace[269265911] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:400; }","duration":"126.970724ms","start":"2026-03-12T19:11:07.332115Z","end":"2026-03-12T19:11:07.459085Z","steps":["trace[269265911] 'agreement among raft nodes before linearized reading' (duration: 126.384378ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:08.049738Z","caller":"traceutil/trace.go:171","msg":"trace[1812537281] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"179.62914ms","start":"2026-03-12T19:11:07.870078Z","end":"2026-03-12T19:11:08.049707Z","steps":["trace[1812537281] 'process raft request' (duration: 179.341587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:08.297644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.472395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-03-12T19:11:08.297748Z","caller":"traceutil/trace.go:171","msg":"trace[578096037] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:401; }","duration":"175.646581ms","start":"2026-03-12T19:11:08.12208Z","end":"2026-03-12T19:11:08.297727Z","steps":["trace[578096037] 'range keys from in-memory index tree' (duration: 175.349028ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:08.41021Z","caller":"traceutil/trace.go:171","msg":"trace[1407722908] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"103.25142ms","start":"2026-03-12T19:11:08.306932Z","end":"2026-03-12T19:11:08.410183Z","steps":["trace[1407722908] 'process raft request' (duration: 103.087504ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:32.831811Z","caller":"traceutil/trace.go:171","msg":"trace[1301588662] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"477.533292ms","start":"2026-03-12T19:11:32.354243Z","end":"2026-03-12T19:11:32.831777Z","steps":["trace[1301588662] 'process raft request' (duration: 477.300411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:32.832028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:32.354211Z","time spent":"477.709564ms","remote":"127.0.0.1:35910","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-12T19:11:32.832994Z","caller":"traceutil/trace.go:171","msg":"trace[684295743] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"122.485928ms","start":"2026-03-12T19:11:32.710486Z","end":"2026-03-12T19:11:32.832972Z","steps":["trace[684295743] 'process raft request' (duration: 122.258997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:33.39459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.641214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:11:33.394931Z","caller":"traceutil/trace.go:171","msg":"trace[1630940190] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"555.01142ms","start":"2026-03-12T19:11:32.839682Z","end":"2026-03-12T19:11:33.394694Z","steps":["trace[1630940190] 'process raft request' (duration: 421.173662ms)","trace[1630940190] 'compare' (duration: 133.526904ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:33.395023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:32.839667Z","time spent":"555.317924ms","remote":"127.0.0.1:35910","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-12T19:11:33.405262Z","caller":"traceutil/trace.go:171","msg":"trace[1658457629] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"559.398402ms","start":"2026-03-12T19:11:32.845848Z","end":"2026-03-12T19:11:33.405247Z","steps":["trace[1658457629] 'process raft request' (duration: 559.174573ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:33.405274Z","caller":"traceutil/trace.go:171","msg":"trace[246220173] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:551; }","duration":"400.758203ms","start":"2026-03-12T19:11:33.004487Z","end":"2026-03-12T19:11:33.405245Z","steps":["trace[246220173] 'read index received' (duration: 256.496937ms)","trace[246220173] 'applied index is now lower than readState.Index' (duration: 144.260285ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:33.405324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:32.845822Z","time spent":"559.470115ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:11:33.405311Z","caller":"traceutil/trace.go:171","msg":"trace[1735563244] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"559.346833ms","start":"2026-03-12T19:11:32.845935Z","end":"2026-03-12T19:11:33.405282Z","steps":["trace[1735563244] 'process raft request' (duration: 559.227012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:33.405407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:32.845931Z","time spent":"559.428978ms","remote":"127.0.0.1:35910","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-12T19:11:33.405438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.926363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:11:33.405467Z","caller":"traceutil/trace.go:171","msg":"trace[149603832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"401.033671ms","start":"2026-03-12T19:11:33.004424Z","end":"2026-03-12T19:11:33.405458Z","steps":["trace[149603832] 'agreement among raft nodes before linearized reading' (duration: 400.949066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:33.405498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:33.004397Z","time spent":"401.095062ms","remote":"127.0.0.1:35634","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-12T19:11:33.405396Z","caller":"traceutil/trace.go:171","msg":"trace[1986453159] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"559.338422ms","start":"2026-03-12T19:11:32.846005Z","end":"2026-03-12T19:11:33.405343Z","steps":["trace[1986453159] 'process raft request' (duration: 559.203698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:33.405898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:32.845982Z","time spent":"559.576594ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:11:39.375967Z","caller":"traceutil/trace.go:171","msg":"trace[1135774022] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"154.328556ms","start":"2026-03-12T19:11:39.221611Z","end":"2026-03-12T19:11:39.37594Z","steps":["trace[1135774022] 'process raft request' (duration: 154.115792ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:39.565745Z","caller":"traceutil/trace.go:171","msg":"trace[1285247149] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"137.630993ms","start":"2026-03-12T19:11:39.428079Z","end":"2026-03-12T19:11:39.56571Z","steps":["trace[1285247149] 'read index received' (duration: 59.098566ms)","trace[1285247149] 'applied index is now lower than readState.Index' (duration: 78.531227ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:39.565915Z","caller":"traceutil/trace.go:171","msg":"trace[1488675112] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"143.160198ms","start":"2026-03-12T19:11:39.422742Z","end":"2026-03-12T19:11:39.565902Z","steps":["trace[1488675112] 'process raft request' (duration: 64.417397ms)","trace[1488675112] 'compare' (duration: 78.278857ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:39.566163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.827504ms","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-12T19:11:39.566241Z","caller":"traceutil/trace.go:171","msg":"trace[833103209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"137.962537ms","start":"2026-03-12T19:11:39.428255Z","end":"2026-03-12T19:11:39.566218Z","steps":["trace[833103209] 'agreement among raft nodes before linearized reading' (duration: 137.73277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:39.566433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.994331ms","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-12T19:11:39.566451Z","caller":"traceutil/trace.go:171","msg":"trace[774041815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"138.013985ms","start":"2026-03-12T19:11:39.428431Z","end":"2026-03-12T19:11:39.566445Z","steps":["trace[774041815] 'agreement among raft nodes before linearized reading' (duration: 137.954955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:39.566459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.354189ms","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-12T19:11:39.566503Z","caller":"traceutil/trace.go:171","msg":"trace[1256497293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"138.458176ms","start":"2026-03-12T19:11:39.42803Z","end":"2026-03-12T19:11:39.566488Z","steps":["trace[1256497293] 'agreement among raft nodes before linearized reading' (duration: 138.282458ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:40.27382Z","caller":"traceutil/trace.go:171","msg":"trace[1150345439] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:592; }","duration":"269.160232ms","start":"2026-03-12T19:11:40.004629Z","end":"2026-03-12T19:11:40.273789Z","steps":["trace[1150345439] 'read index received' (duration: 268.818468ms)","trace[1150345439] 'applied index is now lower than readState.Index' (duration: 340.344µs)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:40.273968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.321168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:11:40.27401Z","caller":"traceutil/trace.go:171","msg":"trace[1153294718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"269.426984ms","start":"2026-03-12T19:11:40.004578Z","end":"2026-03-12T19:11:40.274005Z","steps":["trace[1153294718] 'agreement among raft nodes before linearized reading' (duration: 269.341561ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:40.273869Z","caller":"traceutil/trace.go:171","msg":"trace[1646379515] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"702.684316ms","start":"2026-03-12T19:11:39.571156Z","end":"2026-03-12T19:11:40.27384Z","steps":["trace[1646379515] 'process raft request' (duration: 702.572339ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:40.273845Z","caller":"traceutil/trace.go:171","msg":"trace[1692290433] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"703.01648ms","start":"2026-03-12T19:11:39.570781Z","end":"2026-03-12T19:11:40.273798Z","steps":["trace[1692290433] 'process raft request' (duration: 702.715282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:40.274287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:39.57113Z","time spent":"703.011719ms","remote":"127.0.0.1:35910","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-12T19:11:40.274318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:39.570773Z","time spent":"703.408312ms","remote":"127.0.0.1:35910","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-12T19:11:42.779266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190882049s","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-12T19:11:42.77937Z","caller":"traceutil/trace.go:171","msg":"trace[596594220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"1.191056166s","start":"2026-03-12T19:11:41.588289Z","end":"2026-03-12T19:11:42.779345Z","steps":["trace[596594220] 'range keys from in-memory index tree' (duration: 1.190677097s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:42.77942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:41.588261Z","time spent":"1.19114868s","remote":"127.0.0.1:35910","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-12T19:11:42.779607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.682282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:11:42.779725Z","caller":"traceutil/trace.go:171","msg":"trace[514862779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"684.86386ms","start":"2026-03-12T19:11:42.09484Z","end":"2026-03-12T19:11:42.779704Z","steps":["trace[514862779] 'range keys from in-memory index tree' (duration: 684.574456ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:42.779768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:42.094814Z","time spent":"684.943383ms","remote":"127.0.0.1:35634","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-12T19:11:42.779924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210169442s","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-12T19:11:42.779957Z","caller":"traceutil/trace.go:171","msg":"trace[1223884543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"1.210249424s","start":"2026-03-12T19:11:41.569697Z","end":"2026-03-12T19:11:42.779946Z","steps":["trace[1223884543] 'range keys from in-memory index tree' (duration: 1.209983304s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:42.779983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:41.569671Z","time spent":"1.210306443s","remote":"127.0.0.1:35910","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-12T19:11:42.780105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.074814ms","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-12T19:11:42.780138Z","caller":"traceutil/trace.go:171","msg":"trace[1062488267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"501.156967ms","start":"2026-03-12T19:11:42.278971Z","end":"2026-03-12T19:11:42.780128Z","steps":["trace[1062488267] 'range keys from in-memory index tree' (duration: 500.9088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:42.780163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:42.278943Z","time spent":"501.214137ms","remote":"127.0.0.1:35910","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-12T19:11:42.780269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.775231448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:11:42.780302Z","caller":"traceutil/trace.go:171","msg":"trace[1813154674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"1.77531041s","start":"2026-03-12T19:11:41.004982Z","end":"2026-03-12T19:11:42.780293Z","steps":["trace[1813154674] 'range keys from in-memory index tree' (duration: 1.77511958s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:42.780328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:41.004954Z","time spent":"1.775368109s","remote":"127.0.0.1:35634","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-12T19:11:42.780351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.919982ms","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-12T19:11:42.780382Z","caller":"traceutil/trace.go:171","msg":"trace[9898690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"473.993383ms","start":"2026-03-12T19:11:42.306378Z","end":"2026-03-12T19:11:42.780371Z","steps":["trace[9898690] 'range keys from in-memory index tree' (duration: 473.744065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:42.780407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:42.30635Z","time spent":"474.051732ms","remote":"127.0.0.1:35910","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-12T19:11:43.373865Z","caller":"traceutil/trace.go:171","msg":"trace[455021227] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"195.027137ms","start":"2026-03-12T19:11:43.178808Z","end":"2026-03-12T19:11:43.373835Z","steps":["trace[455021227] 'process raft request' (duration: 194.852931ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:43.375409Z","caller":"traceutil/trace.go:171","msg":"trace[929210714] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"156.0802ms","start":"2026-03-12T19:11:43.219306Z","end":"2026-03-12T19:11:43.375386Z","steps":["trace[929210714] 'read index received' (duration: 154.332946ms)","trace[929210714] 'applied index is now lower than readState.Index' (duration: 1.745943ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:43.375449Z","caller":"traceutil/trace.go:171","msg":"trace[163021326] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"188.355231ms","start":"2026-03-12T19:11:43.187073Z","end":"2026-03-12T19:11:43.375428Z","steps":["trace[163021326] 'process raft request' (duration: 188.14629ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:43.3755Z","caller":"traceutil/trace.go:171","msg":"trace[551852916] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"154.373513ms","start":"2026-03-12T19:11:43.221095Z","end":"2026-03-12T19:11:43.375469Z","steps":["trace[551852916] 'process raft request' (duration: 154.251935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:43.375647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.304314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x2pb9\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-03-12T19:11:43.375678Z","caller":"traceutil/trace.go:171","msg":"trace[711574113] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x2pb9; range_end:; response_count:1; response_revision:582; }","duration":"156.429062ms","start":"2026-03-12T19:11:43.21924Z","end":"2026-03-12T19:11:43.375669Z","steps":["trace[711574113] 'agreement among raft nodes before linearized reading' (duration: 156.228222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.974786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.361987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:11:44.975109Z","caller":"traceutil/trace.go:171","msg":"trace[468695892] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"609.637622ms","start":"2026-03-12T19:11:44.365402Z","end":"2026-03-12T19:11:44.975039Z","steps":["trace[468695892] 'process raft request' (duration: 199.90289ms)","trace[468695892] 'compare' (duration: 408.442351ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:44.975334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:44.36539Z","time spent":"609.810937ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:11:44.97678Z","caller":"traceutil/trace.go:171","msg":"trace[2127729180] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"607.016393ms","start":"2026-03-12T19:11:44.369743Z","end":"2026-03-12T19:11:44.97676Z","steps":["trace[2127729180] 'read index received' (duration: 195.618473ms)","trace[2127729180] 'applied index is now lower than readState.Index' (duration: 411.396709ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:44.976835Z","caller":"traceutil/trace.go:171","msg":"trace[619124942] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"607.796789ms","start":"2026-03-12T19:11:44.369021Z","end":"2026-03-12T19:11:44.976818Z","steps":["trace[619124942] 'process raft request' (duration: 607.444357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.977304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.955566ms","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-12T19:11:44.977337Z","caller":"traceutil/trace.go:171","msg":"trace[755557921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"115.001443ms","start":"2026-03-12T19:11:44.862328Z","end":"2026-03-12T19:11:44.97733Z","steps":["trace[755557921] 'agreement among raft nodes before linearized reading' (duration: 114.887786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.977423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.029211ms","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":"warn","ts":"2026-03-12T19:11:44.977478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.815773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:11:44.977498Z","caller":"traceutil/trace.go:171","msg":"trace[1172797681] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"606.845428ms","start":"2026-03-12T19:11:44.370645Z","end":"2026-03-12T19:11:44.97749Z","steps":["trace[1172797681] 'agreement among raft nodes before linearized reading' (duration: 606.822645ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:44.977504Z","caller":"traceutil/trace.go:171","msg":"trace[587290716] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"217.135837ms","start":"2026-03-12T19:11:44.760348Z","end":"2026-03-12T19:11:44.977484Z","steps":["trace[587290716] 'agreement among raft nodes before linearized reading' (duration: 216.979094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.977541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.166105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:11:44.977556Z","caller":"traceutil/trace.go:171","msg":"trace[1387365024] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"607.192959ms","start":"2026-03-12T19:11:44.370359Z","end":"2026-03-12T19:11:44.977552Z","steps":["trace[1387365024] 'agreement among raft nodes before linearized reading' (duration: 607.169786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.97757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:44.370355Z","time spent":"607.212002ms","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-12T19:11:44.977455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.371085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:11:44.977732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.825785ms","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-12T19:11:44.977732Z","caller":"traceutil/trace.go:171","msg":"trace[253257959] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"417.680902ms","start":"2026-03-12T19:11:44.560035Z","end":"2026-03-12T19:11:44.977716Z","steps":["trace[253257959] 'agreement among raft nodes before linearized reading' (duration: 417.40242ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:44.977751Z","caller":"traceutil/trace.go:171","msg":"trace[607855016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"115.864001ms","start":"2026-03-12T19:11:44.86188Z","end":"2026-03-12T19:11:44.977744Z","steps":["trace[607855016] 'agreement among raft nodes before linearized reading' (duration: 115.814043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.977792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:44.560007Z","time spent":"417.768454ms","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-03-12T19:11:44.977042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.271961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:11:44.977515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:44.370634Z","time spent":"606.875612ms","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-03-12T19:11:44.977731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.907914ms","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-12T19:11:44.976876Z","caller":"traceutil/trace.go:171","msg":"trace[839560780] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"607.044928ms","start":"2026-03-12T19:11:44.369755Z","end":"2026-03-12T19:11:44.9768Z","steps":["trace[839560780] 'process raft request' (duration: 606.872632ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:44.978014Z","caller":"traceutil/trace.go:171","msg":"trace[1242349811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"118.188135ms","start":"2026-03-12T19:11:44.859776Z","end":"2026-03-12T19:11:44.977964Z","steps":["trace[1242349811] 'agreement among raft nodes before linearized reading' (duration: 117.839204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.978041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.078061ms","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-12T19:11:44.978062Z","caller":"traceutil/trace.go:171","msg":"trace[208734696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"117.129729ms","start":"2026-03-12T19:11:44.860926Z","end":"2026-03-12T19:11:44.978056Z","steps":["trace[208734696] 'agreement among raft nodes before linearized reading' (duration: 117.075431ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:44.977884Z","caller":"traceutil/trace.go:171","msg":"trace[1845494128] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"608.158052ms","start":"2026-03-12T19:11:44.369716Z","end":"2026-03-12T19:11:44.977874Z","steps":["trace[1845494128] 'agreement among raft nodes before linearized reading' (duration: 607.222353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.978156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:44.369699Z","time spent":"608.450676ms","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-03-12T19:11:44.977094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.998211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:11:44.978218Z","caller":"traceutil/trace.go:171","msg":"trace[1367026048] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"608.148911ms","start":"2026-03-12T19:11:44.370062Z","end":"2026-03-12T19:11:44.97821Z","steps":["trace[1367026048] 'agreement among raft nodes before linearized reading' (duration: 607.018713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.978234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:44.37005Z","time spent":"608.178776ms","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-03-12T19:11:44.976883Z","caller":"traceutil/trace.go:171","msg":"trace[1056897555] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"606.244679ms","start":"2026-03-12T19:11:44.370603Z","end":"2026-03-12T19:11:44.976848Z","steps":["trace[1056897555] 'process raft request' (duration: 606.112079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:44.979092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:44.370574Z","time spent":"608.486001ms","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:11:44.979962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:44.368993Z","time spent":"608.134199ms","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:11:44.982563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:44.369741Z","time spent":"612.735492ms","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:13:05.495978Z","caller":"traceutil/trace.go:171","msg":"trace[1304031144] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"700.986357ms","start":"2026-03-12T19:13:04.794964Z","end":"2026-03-12T19:13:05.495951Z","steps":["trace[1304031144] 'process raft request' (duration: 700.756016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:05.496303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:04.794937Z","time spent":"701.170105ms","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:13:05.655281Z","caller":"traceutil/trace.go:171","msg":"trace[1670044123] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"695.338113ms","start":"2026-03-12T19:13:04.959921Z","end":"2026-03-12T19:13:05.65526Z","steps":["trace[1670044123] 'process raft request' (duration: 691.574621ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:13:05.655733Z","caller":"traceutil/trace.go:171","msg":"trace[1833846467] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"499.132033ms","start":"2026-03-12T19:13:05.156566Z","end":"2026-03-12T19:13:05.655698Z","steps":["trace[1833846467] 'process raft request' (duration: 498.63194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:05.655482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:04.959902Z","time spent":"695.506761ms","remote":"127.0.0.1:35910","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-12T19:13:05.656221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:05.156537Z","time spent":"499.343872ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:13:05.657636Z","caller":"traceutil/trace.go:171","msg":"trace[1221608663] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1024; }","duration":"653.339ms","start":"2026-03-12T19:13:05.004278Z","end":"2026-03-12T19:13:05.657617Z","steps":["trace[1221608663] 'read index received' (duration: 491.464162ms)","trace[1221608663] 'applied index is now lower than readState.Index' (duration: 161.873518ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:13:05.657838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.531339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:13:05.657924Z","caller":"traceutil/trace.go:171","msg":"trace[1807166904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"653.677695ms","start":"2026-03-12T19:13:05.004226Z","end":"2026-03-12T19:13:05.657904Z","steps":["trace[1807166904] 'agreement among raft nodes before linearized reading' (duration: 653.510557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:05.657973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:05.004201Z","time spent":"653.764149ms","remote":"127.0.0.1:35634","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-12T19:13:14.223406Z","caller":"traceutil/trace.go:171","msg":"trace[1005889341] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"479.614665ms","start":"2026-03-12T19:13:13.74377Z","end":"2026-03-12T19:13:14.223385Z","steps":["trace[1005889341] 'read index received' (duration: 479.434147ms)","trace[1005889341] 'applied index is now lower than readState.Index' (duration: 179.388µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:13:14.223549Z","caller":"traceutil/trace.go:171","msg":"trace[457745064] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"482.17559ms","start":"2026-03-12T19:13:13.741363Z","end":"2026-03-12T19:13:14.223539Z","steps":["trace[457745064] 'process raft request' (duration: 481.888648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:14.223746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:13.741341Z","time spent":"482.327656ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:13:14.223835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.723432ms","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-12T19:13:14.223906Z","caller":"traceutil/trace.go:171","msg":"trace[1709600387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"473.817286ms","start":"2026-03-12T19:13:13.750069Z","end":"2026-03-12T19:13:14.223887Z","steps":["trace[1709600387] 'agreement among raft nodes before linearized reading' (duration: 473.632389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:14.223973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:13.750062Z","time spent":"473.902919ms","remote":"127.0.0.1:35910","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-12T19:13:14.223975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.051528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:13:14.224049Z","caller":"traceutil/trace.go:171","msg":"trace[1812255768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"217.158643ms","start":"2026-03-12T19:13:14.00687Z","end":"2026-03-12T19:13:14.224029Z","steps":["trace[1812255768] 'agreement among raft nodes before linearized reading' (duration: 217.014407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:14.224223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.460429ms","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-12T19:13:14.224282Z","caller":"traceutil/trace.go:171","msg":"trace[536664866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"480.568753ms","start":"2026-03-12T19:13:13.743696Z","end":"2026-03-12T19:13:14.224265Z","steps":["trace[536664866] 'agreement among raft nodes before linearized reading' (duration: 480.432788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:14.224305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.345049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-12T19:13:14.224378Z","caller":"traceutil/trace.go:171","msg":"trace[1045520391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"449.443553ms","start":"2026-03-12T19:13:13.774916Z","end":"2026-03-12T19:13:14.224359Z","steps":["trace[1045520391] 'agreement among raft nodes before linearized reading' (duration: 449.179653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:14.224431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:13.774897Z","time spent":"449.521057ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-12T19:13:14.224322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:13.74367Z","time spent":"480.641706ms","remote":"127.0.0.1:35910","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-12T19:13:14.224593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.339664ms","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-12T19:13:14.22468Z","caller":"traceutil/trace.go:171","msg":"trace[757233791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"454.443159ms","start":"2026-03-12T19:13:13.770224Z","end":"2026-03-12T19:13:14.224667Z","steps":["trace[757233791] 'agreement among raft nodes before linearized reading' (duration: 453.862125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:14.22471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:13.770214Z","time spent":"454.488751ms","remote":"127.0.0.1:35910","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-12T19:13:14.642739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.109438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:13:14.64286Z","caller":"traceutil/trace.go:171","msg":"trace[2034430038] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"413.799492ms","start":"2026-03-12T19:13:14.229035Z","end":"2026-03-12T19:13:14.642834Z","steps":["trace[2034430038] 'process raft request' (duration: 217.495865ms)","trace[2034430038] 'compare' (duration: 195.825357ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:13:14.642944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:14.229018Z","time spent":"413.881235ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:13:14.643645Z","caller":"traceutil/trace.go:171","msg":"trace[1742873076] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"413.940418ms","start":"2026-03-12T19:13:14.229656Z","end":"2026-03-12T19:13:14.643596Z","steps":["trace[1742873076] 'process raft request' (duration: 413.893286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:14.643754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:14.22965Z","time spent":"414.055612ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:13:14.644057Z","caller":"traceutil/trace.go:171","msg":"trace[700592132] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"414.843724ms","start":"2026-03-12T19:13:14.229139Z","end":"2026-03-12T19:13:14.643982Z","steps":["trace[700592132] 'process raft request' (duration: 414.279691ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:13:14.644162Z","caller":"traceutil/trace.go:171","msg":"trace[27323842] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"414.822054ms","start":"2026-03-12T19:13:14.229319Z","end":"2026-03-12T19:13:14.644141Z","steps":["trace[27323842] 'process raft request' (duration: 414.177387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:13:14.644228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:14.229126Z","time spent":"415.016982ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:13:14.644268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:13:14.229312Z","time spent":"414.904827ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}