{"level":"warn","ts":"2026-04-20T10:42:22.846225Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T10:42:22.846303Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69: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.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69: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-04-20T10:42:22.846396Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T10:42:22.846408Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-20T10:42:22.846424Z","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-04-20T10:42:22.847107Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-04-20T10:42:22.847176Z","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.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69: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-04-20T10:42:22.852292Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.900923ms"} {"level":"info","ts":"2026-04-20T10:42:22.86744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-04-20T10:42:22.867567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:42:22.867602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-04-20T10:42:22.867627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:42:22.867649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-04-20T10:42:22.867705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-04-20T10:42:22.886359Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:42:22.890914Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:42:22.901333Z","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-04-20T10:42:22.905758Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:42:22.90616Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:42:22.906204Z","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-04-20T10:42:22.906386Z","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-04-20T10:42:22.906413Z","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-04-20T10:42:22.907329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-04-20T10:42:22.909Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-20T10:42:22.90994Z","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-04-20T10:42:22.910168Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:42:22.910193Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:42:22.911084Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-20T10:42:22.911109Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-20T10:42:23.76874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:42:23.768816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:42:23.768858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-04-20T10:42:23.76888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:42:23.768894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-20T10:42:23.768913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-04-20T10:42:23.768929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-20T10:42:23.769849Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:42:23.769878Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:42:23.769923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:42:23.769981Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:23.77031Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:42:23.77034Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:42:23.770914Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:23.771014Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:23.77105Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:23.772518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"info","ts":"2026-04-20T10:42:23.7821Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-20T10:42:48.471739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.037783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:42:48.471843Z","caller":"traceutil/trace.go:171","msg":"trace[1345402726] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:456; }","duration":"101.203116ms","start":"2026-04-20T10:42:48.370625Z","end":"2026-04-20T10:42:48.471828Z","steps":["trace[1345402726] 'range keys from in-memory index tree' (duration: 100.956972ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:51.861843Z","caller":"traceutil/trace.go:171","msg":"trace[981352714] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"165.217595ms","start":"2026-04-20T10:42:51.696589Z","end":"2026-04-20T10:42:51.861807Z","steps":["trace[981352714] 'process raft request' (duration: 160.777355ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:52.185176Z","caller":"traceutil/trace.go:171","msg":"trace[2102150273] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"250.680949ms","start":"2026-04-20T10:42:51.934468Z","end":"2026-04-20T10:42:52.185149Z","steps":["trace[2102150273] 'process raft request' (duration: 250.500836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:52.514028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.862897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T10:42:52.514109Z","caller":"traceutil/trace.go:171","msg":"trace[2048999600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"249.000829ms","start":"2026-04-20T10:42:52.26509Z","end":"2026-04-20T10:42:52.514091Z","steps":["trace[2048999600] 'range keys from in-memory index tree' (duration: 248.657583ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:54.669588Z","caller":"traceutil/trace.go:171","msg":"trace[1531816554] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"135.725332ms","start":"2026-04-20T10:42:54.533838Z","end":"2026-04-20T10:42:54.669564Z","steps":["trace[1531816554] 'process raft request' (duration: 135.56354ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:56.8144Z","caller":"traceutil/trace.go:171","msg":"trace[1654489485] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"112.715326ms","start":"2026-04-20T10:42:56.701668Z","end":"2026-04-20T10:42:56.814383Z","steps":["trace[1654489485] 'read index received' (duration: 112.591094ms)","trace[1654489485] 'applied index is now lower than readState.Index' (duration: 123.832µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:42:56.814496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.824708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T10:42:56.814525Z","caller":"traceutil/trace.go:171","msg":"trace[1398334210] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:583; }","duration":"112.861299ms","start":"2026-04-20T10:42:56.701654Z","end":"2026-04-20T10:42:56.814516Z","steps":["trace[1398334210] 'agreement among raft nodes before linearized reading' (duration: 112.792468ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:56.814639Z","caller":"traceutil/trace.go:171","msg":"trace[572275340] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"137.792179ms","start":"2026-04-20T10:42:56.676842Z","end":"2026-04-20T10:42:56.814634Z","steps":["trace[572275340] 'process raft request' (duration: 137.460763ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:10.971558Z","caller":"traceutil/trace.go:171","msg":"trace[541766485] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"344.663657ms","start":"2026-04-20T10:43:10.626878Z","end":"2026-04-20T10:43:10.971542Z","steps":["trace[541766485] 'process raft request' (duration: 344.276471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:10.972299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:10.62686Z","time spent":"344.751618ms","remote":"127.0.0.1:48508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:43:35.399368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.890386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T10:43:35.399448Z","caller":"traceutil/trace.go:171","msg":"trace[851228416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"115.049349ms","start":"2026-04-20T10:43:35.284381Z","end":"2026-04-20T10:43:35.39943Z","steps":["trace[851228416] 'range keys from in-memory index tree' (duration: 114.61469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:35.399354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.697304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:43:35.399768Z","caller":"traceutil/trace.go:171","msg":"trace[1099139541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:903; }","duration":"114.05767ms","start":"2026-04-20T10:43:35.285592Z","end":"2026-04-20T10:43:35.39965Z","steps":["trace[1099139541] 'range keys from in-memory index tree' (duration: 113.509831ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:35.506468Z","caller":"traceutil/trace.go:171","msg":"trace[825157693] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"102.495653ms","start":"2026-04-20T10:43:35.403945Z","end":"2026-04-20T10:43:35.506441Z","steps":["trace[825157693] 'process raft request' (duration: 97.872588ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:36.902052Z","caller":"traceutil/trace.go:171","msg":"trace[1083282367] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"161.996086ms","start":"2026-04-20T10:43:36.74003Z","end":"2026-04-20T10:43:36.902026Z","steps":["trace[1083282367] 'read index received' (duration: 161.855493ms)","trace[1083282367] 'applied index is now lower than readState.Index' (duration: 139.323µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:43:36.902171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.122789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:43:36.902216Z","caller":"traceutil/trace.go:171","msg":"trace[799606485] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:908; }","duration":"162.216491ms","start":"2026-04-20T10:43:36.739991Z","end":"2026-04-20T10:43:36.902208Z","steps":["trace[799606485] 'agreement among raft nodes before linearized reading' (duration: 162.12967ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:37.549137Z","caller":"traceutil/trace.go:171","msg":"trace[1426912650] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"404.02178ms","start":"2026-04-20T10:43:37.145089Z","end":"2026-04-20T10:43:37.549111Z","steps":["trace[1426912650] 'read index received' (duration: 400.791981ms)","trace[1426912650] 'applied index is now lower than readState.Index' (duration: 3.228439ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:43:37.549365Z","caller":"traceutil/trace.go:171","msg":"trace[1833957956] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"414.023361ms","start":"2026-04-20T10:43:37.135275Z","end":"2026-04-20T10:43:37.549298Z","steps":["trace[1833957956] 'process raft request' (duration: 410.65956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:37.549702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:37.135249Z","time spent":"414.294476ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:43:37.549846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.735112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T10:43:37.550026Z","caller":"traceutil/trace.go:171","msg":"trace[1994812272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"404.949627ms","start":"2026-04-20T10:43:37.145048Z","end":"2026-04-20T10:43:37.549998Z","steps":["trace[1994812272] 'agreement among raft nodes before linearized reading' (duration: 404.55712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:37.550216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:37.145037Z","time spent":"405.15956ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T10:43:43.686868Z","caller":"traceutil/trace.go:171","msg":"trace[346045011] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"100.262001ms","start":"2026-04-20T10:43:43.586584Z","end":"2026-04-20T10:43:43.686846Z","steps":["trace[346045011] 'process raft request' (duration: 100.084948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:44.002928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.027388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:43:44.003047Z","caller":"traceutil/trace.go:171","msg":"trace[163223850] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"310.44571ms","start":"2026-04-20T10:43:43.692582Z","end":"2026-04-20T10:43:44.003027Z","steps":["trace[163223850] 'process raft request' (duration: 91.076666ms)","trace[163223850] 'compare' (duration: 218.656051ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:43:44.003325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:43.69251Z","time spent":"310.767495ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:43:44.509862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.883673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:43:44.510291Z","caller":"traceutil/trace.go:171","msg":"trace[2071235687] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"816.564913ms","start":"2026-04-20T10:43:43.693704Z","end":"2026-04-20T10:43:44.510269Z","steps":["trace[2071235687] 'process raft request' (duration: 477.181462ms)","trace[2071235687] 'compare' (duration: 338.565737ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:43:44.51064Z","caller":"traceutil/trace.go:171","msg":"trace[1815634366] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"816.628115ms","start":"2026-04-20T10:43:43.693981Z","end":"2026-04-20T10:43:44.510609Z","steps":["trace[1815634366] 'process raft request' (duration: 815.987703ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:44.510792Z","caller":"traceutil/trace.go:171","msg":"trace[1706657609] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:962; }","duration":"693.001131ms","start":"2026-04-20T10:43:43.817765Z","end":"2026-04-20T10:43:44.510767Z","steps":["trace[1706657609] 'read index received' (duration: 353.222941ms)","trace[1706657609] 'applied index is now lower than readState.Index' (duration: 339.77519ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:43:44.510782Z","caller":"traceutil/trace.go:171","msg":"trace[360393009] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"647.427178ms","start":"2026-04-20T10:43:43.863288Z","end":"2026-04-20T10:43:44.510715Z","steps":["trace[360393009] 'process raft request' (duration: 647.303636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:44.510947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:43.693931Z","time spent":"816.867409ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:43:44.511012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.778837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"warn","ts":"2026-04-20T10:43:44.511038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:43.863255Z","time spent":"647.646062ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:43:44.511079Z","caller":"traceutil/trace.go:171","msg":"trace[1441500680] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:934; }","duration":"506.897759ms","start":"2026-04-20T10:43:44.004164Z","end":"2026-04-20T10:43:44.511062Z","steps":["trace[1441500680] 'agreement among raft nodes before linearized reading' (duration: 506.768687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:44.510948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:43.69369Z","time spent":"816.651744ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:43:44.511078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.301656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T10:43:44.511133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:44.004143Z","time spent":"506.9772ms","remote":"127.0.0.1:48484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":248,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"info","ts":"2026-04-20T10:43:44.511183Z","caller":"traceutil/trace.go:171","msg":"trace[1406707655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"693.455519ms","start":"2026-04-20T10:43:43.817704Z","end":"2026-04-20T10:43:44.511159Z","steps":["trace[1406707655] 'agreement among raft nodes before linearized reading' (duration: 693.192724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:44.511239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:43.817689Z","time spent":"693.540791ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T10:43:44.710254Z","caller":"traceutil/trace.go:171","msg":"trace[881938518] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"118.838787ms","start":"2026-04-20T10:43:44.591379Z","end":"2026-04-20T10:43:44.710218Z","steps":["trace[881938518] 'read index received' (duration: 82.802376ms)","trace[881938518] 'applied index is now lower than readState.Index' (duration: 36.034911ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:43:44.710511Z","caller":"traceutil/trace.go:171","msg":"trace[223254274] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"156.044209ms","start":"2026-04-20T10:43:44.554437Z","end":"2026-04-20T10:43:44.710482Z","steps":["trace[223254274] 'process raft request' (duration: 119.835585ms)","trace[223254274] 'compare' (duration: 35.781846ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:43:44.710594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.997981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:43:44.710712Z","caller":"traceutil/trace.go:171","msg":"trace[671553213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"119.390118ms","start":"2026-04-20T10:43:44.591307Z","end":"2026-04-20T10:43:44.710697Z","steps":["trace[671553213] 'agreement among raft nodes before linearized reading' (duration: 119.025201ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:44:12.846269Z","caller":"traceutil/trace.go:171","msg":"trace[1537561213] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"115.026671ms","start":"2026-04-20T10:44:12.731222Z","end":"2026-04-20T10:44:12.846248Z","steps":["trace[1537561213] 'process raft request' (duration: 114.972889ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:44:12.846529Z","caller":"traceutil/trace.go:171","msg":"trace[1047860084] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"115.383699ms","start":"2026-04-20T10:44:12.73113Z","end":"2026-04-20T10:44:12.846514Z","steps":["trace[1047860084] 'process raft request' (duration: 80.435234ms)","trace[1047860084] 'compare' (duration: 34.42805ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:44:35.248777Z","caller":"traceutil/trace.go:171","msg":"trace[304782687] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"121.37232ms","start":"2026-04-20T10:44:35.127369Z","end":"2026-04-20T10:44:35.248742Z","steps":["trace[304782687] 'process raft request' (duration: 121.110035ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:44:38.049852Z","caller":"traceutil/trace.go:171","msg":"trace[390353111] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"102.570387ms","start":"2026-04-20T10:44:37.94721Z","end":"2026-04-20T10:44:38.04978Z","steps":["trace[390353111] 'process raft request' (duration: 102.409904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:32.519119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.812711ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T10:46:32.519257Z","caller":"traceutil/trace.go:171","msg":"trace[97912837] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1551; }","duration":"127.725874ms","start":"2026-04-20T10:46:32.391501Z","end":"2026-04-20T10:46:32.519227Z","steps":["trace[97912837] 'read index received' (duration: 48.271µs)","trace[97912837] 'applied index is now lower than readState.Index' (duration: 127.673063ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:32.519355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.843486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:46:32.519423Z","caller":"traceutil/trace.go:171","msg":"trace[405578235] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1487; }","duration":"127.922537ms","start":"2026-04-20T10:46:32.391483Z","end":"2026-04-20T10:46:32.519406Z","steps":["trace[405578235] 'agreement among raft nodes before linearized reading' (duration: 127.808215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:32.966178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.950871ms","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-04-20T10:46:32.966248Z","caller":"traceutil/trace.go:171","msg":"trace[132719213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1488; }","duration":"136.060812ms","start":"2026-04-20T10:46:32.830167Z","end":"2026-04-20T10:46:32.966228Z","steps":["trace[132719213] 'range keys from in-memory index tree' (duration: 135.803368ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:55.375283Z","caller":"traceutil/trace.go:171","msg":"trace[2087883481] linearizableReadLoop","detail":"{readStateIndex:1631; appliedIndex:1630; }","duration":"233.564631ms","start":"2026-04-20T10:46:55.141698Z","end":"2026-04-20T10:46:55.375262Z","steps":["trace[2087883481] 'read index received' (duration: 233.4967ms)","trace[2087883481] 'applied index is now lower than readState.Index' (duration: 66.851µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:55.375484Z","caller":"traceutil/trace.go:171","msg":"trace[1999170290] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"304.306698ms","start":"2026-04-20T10:46:55.071158Z","end":"2026-04-20T10:46:55.375465Z","steps":["trace[1999170290] 'process raft request' (duration: 303.973172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:55.375532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.817526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:46:55.375566Z","caller":"traceutil/trace.go:171","msg":"trace[1789104909] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1562; }","duration":"233.897757ms","start":"2026-04-20T10:46:55.141658Z","end":"2026-04-20T10:46:55.375556Z","steps":["trace[1789104909] 'agreement among raft nodes before linearized reading' (duration: 233.787025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:55.375601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:55.07114Z","time spent":"304.385749ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:46:55.945148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.821024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:46:55.945254Z","caller":"traceutil/trace.go:171","msg":"trace[1688284055] linearizableReadLoop","detail":"{readStateIndex:1632; appliedIndex:1631; }","duration":"532.811442ms","start":"2026-04-20T10:46:55.412426Z","end":"2026-04-20T10:46:55.945237Z","steps":["trace[1688284055] 'read index received' (duration: 265.718262ms)","trace[1688284055] 'applied index is now lower than readState.Index' (duration: 267.09202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:55.945558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.116328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T10:46:55.945637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.567157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T10:46:55.94569Z","caller":"traceutil/trace.go:171","msg":"trace[183475293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1563; }","duration":"343.661419ms","start":"2026-04-20T10:46:55.602019Z","end":"2026-04-20T10:46:55.94568Z","steps":["trace[183475293] 'agreement among raft nodes before linearized reading' (duration: 343.518105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:55.945731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:55.601999Z","time spent":"343.72382ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T10:46:55.94584Z","caller":"traceutil/trace.go:171","msg":"trace[501691964] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1563; }","duration":"533.307242ms","start":"2026-04-20T10:46:55.412367Z","end":"2026-04-20T10:46:55.945674Z","steps":["trace[501691964] 'agreement among raft nodes before linearized reading' (duration: 533.059406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:55.946008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:55.412344Z","time spent":"533.646989ms","remote":"127.0.0.1:48380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:46:55.945606Z","caller":"traceutil/trace.go:171","msg":"trace[994238835] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"623.370888ms","start":"2026-04-20T10:46:55.322209Z","end":"2026-04-20T10:46:55.94558Z","steps":["trace[994238835] 'process raft request' (duration: 356.037355ms)","trace[994238835] 'compare' (duration: 266.719352ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:55.946207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:55.322183Z","time spent":"623.957499ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:46:56.142969Z","caller":"traceutil/trace.go:171","msg":"trace[470728728] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"125.889018ms","start":"2026-04-20T10:46:56.01706Z","end":"2026-04-20T10:46:56.142949Z","steps":["trace[470728728] 'process raft request' (duration: 125.799286ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:56.932156Z","caller":"traceutil/trace.go:171","msg":"trace[84687291] linearizableReadLoop","detail":"{readStateIndex:1636; appliedIndex:1635; }","duration":"144.182998ms","start":"2026-04-20T10:46:56.78795Z","end":"2026-04-20T10:46:56.932133Z","steps":["trace[84687291] 'read index received' (duration: 143.958343ms)","trace[84687291] 'applied index is now lower than readState.Index' (duration: 223.505µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:56.932347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.385731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T10:46:56.932388Z","caller":"traceutil/trace.go:171","msg":"trace[935564389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1566; }","duration":"144.467673ms","start":"2026-04-20T10:46:56.78791Z","end":"2026-04-20T10:46:56.932377Z","steps":["trace[935564389] 'agreement among raft nodes before linearized reading' (duration: 144.346531ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:57.128518Z","caller":"traceutil/trace.go:171","msg":"trace[142441369] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"192.263283ms","start":"2026-04-20T10:46:56.936229Z","end":"2026-04-20T10:46:57.128492Z","steps":["trace[142441369] 'process raft request' (duration: 192.159972ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:57.12856Z","caller":"traceutil/trace.go:171","msg":"trace[1034904400] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"195.130707ms","start":"2026-04-20T10:46:56.93336Z","end":"2026-04-20T10:46:57.128491Z","steps":["trace[1034904400] 'process raft request' (duration: 143.216798ms)","trace[1034904400] 'compare' (duration: 51.643275ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:58.276007Z","caller":"traceutil/trace.go:171","msg":"trace[1219646530] linearizableReadLoop","detail":"{readStateIndex:1641; appliedIndex:1640; }","duration":"166.646326ms","start":"2026-04-20T10:46:58.109334Z","end":"2026-04-20T10:46:58.27598Z","steps":["trace[1219646530] 'read index received' (duration: 166.448962ms)","trace[1219646530] 'applied index is now lower than readState.Index' (duration: 196.324µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:58.276146Z","caller":"traceutil/trace.go:171","msg":"trace[2013231619] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"255.713993ms","start":"2026-04-20T10:46:58.020414Z","end":"2026-04-20T10:46:58.276128Z","steps":["trace[2013231619] 'process raft request' (duration: 255.428828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:58.276434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.199951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T10:46:58.276673Z","caller":"traceutil/trace.go:171","msg":"trace[122500940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1571; }","duration":"130.382714ms","start":"2026-04-20T10:46:58.146178Z","end":"2026-04-20T10:46:58.276561Z","steps":["trace[122500940] 'agreement among raft nodes before linearized reading' (duration: 130.072419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:58.277035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.681605ms","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-04-20T10:46:58.277224Z","caller":"traceutil/trace.go:171","msg":"trace[341067418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1571; }","duration":"167.91559ms","start":"2026-04-20T10:46:58.109284Z","end":"2026-04-20T10:46:58.2772Z","steps":["trace[341067418] 'agreement among raft nodes before linearized reading' (duration: 167.175275ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:58.464744Z","caller":"traceutil/trace.go:171","msg":"trace[1966067708] linearizableReadLoop","detail":"{readStateIndex:1643; appliedIndex:1641; }","duration":"117.125742ms","start":"2026-04-20T10:46:58.347593Z","end":"2026-04-20T10:46:58.464718Z","steps":["trace[1966067708] 'read index received' (duration: 86.130902ms)","trace[1966067708] 'applied index is now lower than readState.Index' (duration: 30.9936ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:58.464807Z","caller":"traceutil/trace.go:171","msg":"trace[727139303] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"184.307032ms","start":"2026-04-20T10:46:58.280466Z","end":"2026-04-20T10:46:58.464773Z","steps":["trace[727139303] 'process raft request' (duration: 153.248ms)","trace[727139303] 'compare' (duration: 30.751077ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:58.464893Z","caller":"traceutil/trace.go:171","msg":"trace[795852282] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"183.6711ms","start":"2026-04-20T10:46:58.281205Z","end":"2026-04-20T10:46:58.464876Z","steps":["trace[795852282] 'process raft request' (duration: 183.451656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:58.465055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.444078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:58.465207Z","caller":"traceutil/trace.go:171","msg":"trace[2056183192] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1573; }","duration":"117.56444ms","start":"2026-04-20T10:46:58.34755Z","end":"2026-04-20T10:46:58.465114Z","steps":["trace[2056183192] 'agreement among raft nodes before linearized reading' (duration: 117.413708ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:13.36568Z","caller":"traceutil/trace.go:171","msg":"trace[932505014] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"158.179035ms","start":"2026-04-20T10:47:13.207482Z","end":"2026-04-20T10:47:13.365661Z","steps":["trace[932505014] 'process raft request' (duration: 158.059113ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:18.565364Z","caller":"traceutil/trace.go:171","msg":"trace[531831326] linearizableReadLoop","detail":"{readStateIndex:1765; appliedIndex:1764; }","duration":"144.24435ms","start":"2026-04-20T10:47:18.421102Z","end":"2026-04-20T10:47:18.565346Z","steps":["trace[531831326] 'read index received' (duration: 144.133508ms)","trace[531831326] 'applied index is now lower than readState.Index' (duration: 109.822µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:18.565469Z","caller":"traceutil/trace.go:171","msg":"trace[1673070253] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"158.026252ms","start":"2026-04-20T10:47:18.40741Z","end":"2026-04-20T10:47:18.565436Z","steps":["trace[1673070253] 'process raft request' (duration: 157.824649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:18.565524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.395362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:18.565574Z","caller":"traceutil/trace.go:171","msg":"trace[800051756] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1689; }","duration":"144.479394ms","start":"2026-04-20T10:47:18.421075Z","end":"2026-04-20T10:47:18.565555Z","steps":["trace[800051756] 'agreement among raft nodes before linearized reading' (duration: 144.401053ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:29.051201Z","caller":"traceutil/trace.go:171","msg":"trace[1411716656] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"140.817439ms","start":"2026-04-20T10:47:28.910346Z","end":"2026-04-20T10:47:29.051163Z","steps":["trace[1411716656] 'process raft request' (duration: 140.601466ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:29.055336Z","caller":"traceutil/trace.go:171","msg":"trace[1078119429] transaction","detail":"{read_only:false; response_revision:1755; number_of_response:1; }","duration":"135.214491ms","start":"2026-04-20T10:47:28.920095Z","end":"2026-04-20T10:47:29.05531Z","steps":["trace[1078119429] 'process raft request' (duration: 135.049209ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:31.103494Z","caller":"traceutil/trace.go:171","msg":"trace[1012216495] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"306.603019ms","start":"2026-04-20T10:47:30.796856Z","end":"2026-04-20T10:47:31.103459Z","steps":["trace[1012216495] 'process raft request' (duration: 274.721487ms)","trace[1012216495] 'compare' (duration: 31.513416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:31.103642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:30.796829Z","time spent":"306.738481ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:47:31.103478Z","caller":"traceutil/trace.go:171","msg":"trace[497712936] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"302.729931ms","start":"2026-04-20T10:47:30.800719Z","end":"2026-04-20T10:47:31.103449Z","steps":["trace[497712936] 'process raft request' (duration: 302.607689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:31.103879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:30.800709Z","time spent":"303.064816ms","remote":"127.0.0.1:48548","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-04-20T10:47:32.825141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.981886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T10:47:32.825594Z","caller":"traceutil/trace.go:171","msg":"trace[1433410446] linearizableReadLoop","detail":"{readStateIndex:1853; appliedIndex:1852; }","duration":"947.594616ms","start":"2026-04-20T10:47:31.877969Z","end":"2026-04-20T10:47:32.825564Z","steps":["trace[1433410446] 'read index received' (duration: 201.125322ms)","trace[1433410446] 'applied index is now lower than readState.Index' (duration: 746.467144ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:32.825717Z","caller":"traceutil/trace.go:171","msg":"trace[1486998467] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"678.62262ms","start":"2026-04-20T10:47:32.147063Z","end":"2026-04-20T10:47:32.825686Z","steps":["trace[1486998467] 'process raft request' (duration: 678.193943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:32.825873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:32.147033Z","time spent":"678.755383ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:32.997649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119653527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:47:32.997734Z","caller":"traceutil/trace.go:171","msg":"trace[1224067421] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1775; }","duration":"1.119805999s","start":"2026-04-20T10:47:31.877907Z","end":"2026-04-20T10:47:32.997713Z","steps":["trace[1224067421] 'agreement among raft nodes before linearized reading' (duration: 947.80129ms)","trace[1224067421] 'count revisions from in-memory index tree' (duration: 171.872407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:32.99777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:31.877884Z","time spent":"1.119878661s","remote":"127.0.0.1:48414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:47:32.997879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.153241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:32.998035Z","caller":"traceutil/trace.go:171","msg":"trace[730835882] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1854; }","duration":"172.347905ms","start":"2026-04-20T10:47:32.825674Z","end":"2026-04-20T10:47:32.998022Z","steps":["trace[730835882] 'read index received' (duration: 51.361µs)","trace[730835882] 'applied index is now lower than readState.Index' (duration: 172.294704ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:32.998052Z","caller":"traceutil/trace.go:171","msg":"trace[260439534] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"722.661245ms","start":"2026-04-20T10:47:32.275382Z","end":"2026-04-20T10:47:32.998043Z","steps":["trace[260439534] 'process raft request' (duration: 550.28362ms)","trace[260439534] 'compare' (duration: 171.908907ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:32.998098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:32.275357Z","time spent":"722.716346ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:32.998204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.684297ms","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-04-20T10:47:32.998288Z","caller":"traceutil/trace.go:171","msg":"trace[1799974269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1776; }","duration":"203.807768ms","start":"2026-04-20T10:47:32.794457Z","end":"2026-04-20T10:47:32.998265Z","steps":["trace[1799974269] 'agreement among raft nodes before linearized reading' (duration: 203.590444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:32.998277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.499438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/test-pvc.18a80ae8a245b710\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-04-20T10:47:32.998354Z","caller":"traceutil/trace.go:171","msg":"trace[1414724854] range","detail":"{range_begin:/registry/events/default/test-pvc.18a80ae8a245b710; range_end:; response_count:1; response_revision:1776; }","duration":"565.62925ms","start":"2026-04-20T10:47:32.432708Z","end":"2026-04-20T10:47:32.998337Z","steps":["trace[1414724854] 'agreement among raft nodes before linearized reading' (duration: 565.400596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:32.998411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:32.432687Z","time spent":"565.710581ms","remote":"127.0.0.1:48380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":920,"request content":"key:\"/registry/events/default/test-pvc.18a80ae8a245b710\" "} {"level":"warn","ts":"2026-04-20T10:47:33.208934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.671113ms","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-04-20T10:47:33.209022Z","caller":"traceutil/trace.go:171","msg":"trace[310148706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1778; }","duration":"100.814195ms","start":"2026-04-20T10:47:33.108188Z","end":"2026-04-20T10:47:33.209002Z","steps":["trace[310148706] 'range keys from in-memory index tree' (duration: 100.49919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:33.208942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.070431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T10:47:33.209109Z","caller":"traceutil/trace.go:171","msg":"trace[1224493553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1778; }","duration":"101.288484ms","start":"2026-04-20T10:47:33.107803Z","end":"2026-04-20T10:47:33.209091Z","steps":["trace[1224493553] 'range keys from in-memory index tree' (duration: 100.853206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:33.458379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.457006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:33.458797Z","caller":"traceutil/trace.go:171","msg":"trace[1769828818] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"244.794861ms","start":"2026-04-20T10:47:33.213741Z","end":"2026-04-20T10:47:33.458536Z","steps":["trace[1769828818] 'process raft request' (duration: 126.03023ms)","trace[1769828818] 'compare' (duration: 118.271663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:33.944668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.18208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:33.944758Z","caller":"traceutil/trace.go:171","msg":"trace[1135208094] linearizableReadLoop","detail":"{readStateIndex:1860; appliedIndex:1859; }","duration":"354.582554ms","start":"2026-04-20T10:47:33.590162Z","end":"2026-04-20T10:47:33.944744Z","steps":["trace[1135208094] 'read index received' (duration: 223.20495ms)","trace[1135208094] 'applied index is now lower than readState.Index' (duration: 131.376414ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:33.944809Z","caller":"traceutil/trace.go:171","msg":"trace[840900640] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"432.710709ms","start":"2026-04-20T10:47:33.512069Z","end":"2026-04-20T10:47:33.94478Z","steps":["trace[840900640] 'process raft request' (duration: 301.348286ms)","trace[840900640] 'compare' (duration: 131.072348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:33.944936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:33.512049Z","time spent":"432.821191ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:33.945019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.819467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:33.945126Z","caller":"traceutil/trace.go:171","msg":"trace[1849493346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1781; }","duration":"355.007591ms","start":"2026-04-20T10:47:33.5901Z","end":"2026-04-20T10:47:33.945108Z","steps":["trace[1849493346] 'agreement among raft nodes before linearized reading' (duration: 354.828658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:33.9452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:33.590075Z","time spent":"355.115243ms","remote":"127.0.0.1:48316","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-04-20T10:47:33.945205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.350985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:47:33.945248Z","caller":"traceutil/trace.go:171","msg":"trace[122671250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1781; }","duration":"256.446747ms","start":"2026-04-20T10:47:33.68879Z","end":"2026-04-20T10:47:33.945236Z","steps":["trace[122671250] 'agreement among raft nodes before linearized reading' (duration: 256.255093ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:34.370063Z","caller":"traceutil/trace.go:171","msg":"trace[2089841953] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1860; }","duration":"300.536362ms","start":"2026-04-20T10:47:34.069503Z","end":"2026-04-20T10:47:34.37004Z","steps":["trace[2089841953] 'read index received' (duration: 218.65418ms)","trace[2089841953] 'applied index is now lower than readState.Index' (duration: 81.881062ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:34.370176Z","caller":"traceutil/trace.go:171","msg":"trace[449651454] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"420.460714ms","start":"2026-04-20T10:47:33.949675Z","end":"2026-04-20T10:47:34.370136Z","steps":["trace[449651454] 'process raft request' (duration: 338.567732ms)","trace[449651454] 'compare' (duration: 81.692499ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:34.370323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:33.949654Z","time spent":"420.583776ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:34.370347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.738347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-20T10:47:34.370433Z","caller":"traceutil/trace.go:171","msg":"trace[615916913] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1782; }","duration":"300.96665ms","start":"2026-04-20T10:47:34.069458Z","end":"2026-04-20T10:47:34.370425Z","steps":["trace[615916913] 'agreement among raft nodes before linearized reading' (duration: 300.659095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:34.370539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:34.069445Z","time spent":"301.083042ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-20T10:47:54.944927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003661479289968,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T10:47:55.1713Z","caller":"traceutil/trace.go:171","msg":"trace[928156681] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:2001; }","duration":"727.099185ms","start":"2026-04-20T10:47:54.444167Z","end":"2026-04-20T10:47:55.171266Z","steps":["trace[928156681] 'read index received' (duration: 726.820019ms)","trace[928156681] 'applied index is now lower than readState.Index' (duration: 277.966µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:55.171717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.477681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-20T10:47:55.171803Z","caller":"traceutil/trace.go:171","msg":"trace[1613165367] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1919; }","duration":"727.658354ms","start":"2026-04-20T10:47:54.444123Z","end":"2026-04-20T10:47:55.171781Z","steps":["trace[1613165367] 'agreement among raft nodes before linearized reading' (duration: 727.309149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.171855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:54.444102Z","time spent":"727.743986ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-20T10:47:55.172044Z","caller":"traceutil/trace.go:171","msg":"trace[1901831713] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"1.125772595s","start":"2026-04-20T10:47:54.046201Z","end":"2026-04-20T10:47:55.171974Z","steps":["trace[1901831713] 'process raft request' (duration: 1.124838938s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.172289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:54.046184Z","time spent":"1.125978098s","remote":"127.0.0.1:48548","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-04-20T10:47:55.720799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.934763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:55.721059Z","caller":"traceutil/trace.go:171","msg":"trace[1422854024] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2002; }","duration":"549.571517ms","start":"2026-04-20T10:47:55.171407Z","end":"2026-04-20T10:47:55.720978Z","steps":["trace[1422854024] 'read index received' (duration: 438.480181ms)","trace[1422854024] 'applied index is now lower than readState.Index' (duration: 111.090116ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:55.721157Z","caller":"traceutil/trace.go:171","msg":"trace[1860626396] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"655.504312ms","start":"2026-04-20T10:47:55.065626Z","end":"2026-04-20T10:47:55.721131Z","steps":["trace[1860626396] 'process raft request' (duration: 655.261798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.721233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.185291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:47:55.721309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:55.065597Z","time spent":"655.632075ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:55.721322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251880804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:47:55.721362Z","caller":"traceutil/trace.go:171","msg":"trace[1663150872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1922; }","duration":"1.251944846s","start":"2026-04-20T10:47:54.469406Z","end":"2026-04-20T10:47:55.721351Z","steps":["trace[1663150872] 'agreement among raft nodes before linearized reading' (duration: 1.251806614s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.721393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.264635ms","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-04-20T10:47:55.721435Z","caller":"traceutil/trace.go:171","msg":"trace[274467537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1922; }","duration":"610.320297ms","start":"2026-04-20T10:47:55.111105Z","end":"2026-04-20T10:47:55.721425Z","steps":["trace[274467537] 'agreement among raft nodes before linearized reading' (duration: 609.978221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.721387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.287779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:47:55.721469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:55.111093Z","time spent":"610.367358ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T10:47:55.721171Z","caller":"traceutil/trace.go:171","msg":"trace[1198534585] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"543.807396ms","start":"2026-04-20T10:47:55.177341Z","end":"2026-04-20T10:47:55.721148Z","steps":["trace[1198534585] 'process raft request' (duration: 543.606222ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:55.721514Z","caller":"traceutil/trace.go:171","msg":"trace[437160802] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1922; }","duration":"588.467003ms","start":"2026-04-20T10:47:55.133021Z","end":"2026-04-20T10:47:55.721488Z","steps":["trace[437160802] 'agreement among raft nodes before linearized reading' (duration: 588.212378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.721562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:55.177299Z","time spent":"544.224944ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:55.721583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:55.133009Z","time spent":"588.562955ms","remote":"127.0.0.1:48442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-04-20T10:47:55.721317Z","caller":"traceutil/trace.go:171","msg":"trace[1521211738] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"696.357501ms","start":"2026-04-20T10:47:55.024837Z","end":"2026-04-20T10:47:55.721194Z","steps":["trace[1521211738] 'process raft request' (duration: 584.93279ms)","trace[1521211738] 'compare' (duration: 110.831441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:55.721785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.34324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-20T10:47:55.721445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.952649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-20T10:47:55.721831Z","caller":"traceutil/trace.go:171","msg":"trace[1470908045] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1922; }","duration":"383.463253ms","start":"2026-04-20T10:47:55.338355Z","end":"2026-04-20T10:47:55.721818Z","steps":["trace[1470908045] 'agreement among raft nodes before linearized reading' (duration: 383.366681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.721859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:55.338325Z","time spent":"383.528434ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-20T10:47:55.721862Z","caller":"traceutil/trace.go:171","msg":"trace[612537426] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1922; }","duration":"657.413536ms","start":"2026-04-20T10:47:55.064429Z","end":"2026-04-20T10:47:55.721843Z","steps":["trace[612537426] 'agreement among raft nodes before linearized reading' (duration: 656.719814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.721958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:55.064405Z","time spent":"657.487518ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-20T10:47:55.721292Z","caller":"traceutil/trace.go:171","msg":"trace[1851197427] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1922; }","duration":"892.319393ms","start":"2026-04-20T10:47:54.828959Z","end":"2026-04-20T10:47:55.721278Z","steps":["trace[1851197427] 'agreement among raft nodes before linearized reading' (duration: 892.17384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.721431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:54.469394Z","time spent":"1.252026458s","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:47:55.722055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:54.828928Z","time spent":"893.108507ms","remote":"127.0.0.1:48440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:47:55.72195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:55.024762Z","time spent":"697.100125ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:55.722144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.548794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T10:47:55.722173Z","caller":"traceutil/trace.go:171","msg":"trace[981205328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1922; }","duration":"362.634806ms","start":"2026-04-20T10:47:55.35953Z","end":"2026-04-20T10:47:55.722165Z","steps":["trace[981205328] 'agreement among raft nodes before linearized reading' (duration: 362.556755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.722207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:55.359503Z","time spent":"362.698906ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T10:47:55.72219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.839817ms","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-04-20T10:47:55.722263Z","caller":"traceutil/trace.go:171","msg":"trace[562889355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1922; }","duration":"158.962708ms","start":"2026-04-20T10:47:55.563284Z","end":"2026-04-20T10:47:55.722247Z","steps":["trace[562889355] 'agreement among raft nodes before linearized reading' (duration: 158.752085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.722286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.034237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:55.722323Z","caller":"traceutil/trace.go:171","msg":"trace[1266373259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1922; }","duration":"131.094387ms","start":"2026-04-20T10:47:55.591217Z","end":"2026-04-20T10:47:55.722312Z","steps":["trace[1266373259] 'agreement among raft nodes before linearized reading' (duration: 130.985686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.72254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269886612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:47:55.72271Z","caller":"traceutil/trace.go:171","msg":"trace[1276776742] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1922; }","duration":"1.269999574s","start":"2026-04-20T10:47:54.45258Z","end":"2026-04-20T10:47:55.72258Z","steps":["trace[1276776742] 'agreement among raft nodes before linearized reading' (duration: 1.268899415s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:55.722836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:54.452551Z","time spent":"1.270197767s","remote":"127.0.0.1:48648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "}