{"level":"warn","ts":"2026-05-06T00:42:44.411444Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T00:42:44.411551Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.31:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.31:2380","--initial-cluster=instance=https://199.19.213.31:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.31:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.31: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-05-06T00:42:44.411628Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T00:42:44.411637Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.31:2380"]} {"level":"info","ts":"2026-05-06T00:42:44.411654Z","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-05-06T00:42:44.412407Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.31:2379"]} {"level":"info","ts":"2026-05-06T00:42:44.412499Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.31:2380"],"listen-peer-urls":["https://199.19.213.31:2380"],"advertise-client-urls":["https://199.19.213.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.31: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-05-06T00:42:44.420415Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.621038ms"} {"level":"info","ts":"2026-05-06T00:42:44.434521Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b3aa5b67741e7c5e","cluster-id":"9896be1db1fb000"} {"level":"info","ts":"2026-05-06T00:42:44.43462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T00:42:44.434643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became follower at term 0"} {"level":"info","ts":"2026-05-06T00:42:44.434654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b3aa5b67741e7c5e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T00:42:44.43467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became follower at term 1"} {"level":"info","ts":"2026-05-06T00:42:44.434709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e switched to configuration voters=(12946260578717826142)"} {"level":"warn","ts":"2026-05-06T00:42:44.461081Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T00:42:44.467431Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T00:42:44.470592Z","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-05-06T00:42:44.474723Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b3aa5b67741e7c5e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T00:42:44.475678Z","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-05-06T00:42:44.475728Z","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-05-06T00:42:44.475738Z","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-05-06T00:42:44.476779Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b3aa5b67741e7c5e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T00:42:44.477908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e switched to configuration voters=(12946260578717826142)"} {"level":"info","ts":"2026-05-06T00:42:44.478237Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9896be1db1fb000","local-member-id":"b3aa5b67741e7c5e","added-peer-id":"b3aa5b67741e7c5e","added-peer-peer-urls":["https://199.19.213.31:2380"]} {"level":"info","ts":"2026-05-06T00:42:44.479645Z","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-05-06T00:42:44.480005Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b3aa5b67741e7c5e","initial-advertise-peer-urls":["https://199.19.213.31:2380"],"listen-peer-urls":["https://199.19.213.31:2380"],"advertise-client-urls":["https://199.19.213.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T00:42:44.479767Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.31:2380"} {"level":"info","ts":"2026-05-06T00:42:44.480725Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.31:2380"} {"level":"info","ts":"2026-05-06T00:42:44.480116Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T00:42:44.73624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T00:42:44.736328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T00:42:44.736352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e received MsgPreVoteResp from b3aa5b67741e7c5e at term 1"} {"level":"info","ts":"2026-05-06T00:42:44.736367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became candidate at term 2"} {"level":"info","ts":"2026-05-06T00:42:44.736375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e received MsgVoteResp from b3aa5b67741e7c5e at term 2"} {"level":"info","ts":"2026-05-06T00:42:44.736386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became leader at term 2"} {"level":"info","ts":"2026-05-06T00:42:44.736396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b3aa5b67741e7c5e elected leader b3aa5b67741e7c5e at term 2"} {"level":"info","ts":"2026-05-06T00:42:44.738531Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:42:44.740835Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:42:44.740864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:42:44.740824Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b3aa5b67741e7c5e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.31:2379]}","request-path":"/0/members/b3aa5b67741e7c5e/attributes","cluster-id":"9896be1db1fb000","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T00:42:44.743797Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9896be1db1fb000","local-member-id":"b3aa5b67741e7c5e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:42:44.743884Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:42:44.743914Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:42:44.743939Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T00:42:44.743951Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T00:42:44.745221Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T00:42:44.747867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.31:2379"} {"level":"info","ts":"2026-05-06T00:42:51.476512Z","caller":"traceutil/trace.go:171","msg":"trace[493274643] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"164.868696ms","start":"2026-05-06T00:42:51.311613Z","end":"2026-05-06T00:42:51.476482Z","steps":["trace[493274643] 'process raft request' (duration: 163.608659ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:42:52.632901Z","caller":"traceutil/trace.go:171","msg":"trace[1592192306] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"217.579736ms","start":"2026-05-06T00:42:52.41529Z","end":"2026-05-06T00:42:52.632869Z","steps":["trace[1592192306] 'read index received' (duration: 217.243935ms)","trace[1592192306] 'applied index is now lower than readState.Index' (duration: 334.411µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:42:52.632947Z","caller":"traceutil/trace.go:171","msg":"trace[1756679619] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"472.829797ms","start":"2026-05-06T00:42:52.160092Z","end":"2026-05-06T00:42:52.632922Z","steps":["trace[1756679619] 'process raft request' (duration: 472.575776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:52.633312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.760457ms","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-05-06T00:42:52.633371Z","caller":"traceutil/trace.go:171","msg":"trace[822437959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:288; }","duration":"220.612121ms","start":"2026-05-06T00:42:52.412748Z","end":"2026-05-06T00:42:52.63336Z","steps":["trace[822437959] 'agreement among raft nodes before linearized reading' (duration: 220.228519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:52.633746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:42:52.160058Z","time spent":"472.942078ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:42:53.443335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.701174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-06T00:42:53.443433Z","caller":"traceutil/trace.go:171","msg":"trace[2118405628] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:288; }","duration":"806.836285ms","start":"2026-05-06T00:42:52.636575Z","end":"2026-05-06T00:42:53.443411Z","steps":["trace[2118405628] 'range keys from in-memory index tree' (duration: 806.626483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:53.443488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:42:52.636561Z","time spent":"806.917255ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-06T00:42:53.443989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.559931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:42:53.444332Z","caller":"traceutil/trace.go:171","msg":"trace[1276756115] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"477.432656ms","start":"2026-05-06T00:42:52.966884Z","end":"2026-05-06T00:42:53.444316Z","steps":["trace[1276756115] 'read index received' (duration: 68.88856ms)","trace[1276756115] 'applied index is now lower than readState.Index' (duration: 408.542666ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:42:53.444456Z","caller":"traceutil/trace.go:171","msg":"trace[782759526] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"807.431967ms","start":"2026-05-06T00:42:52.636978Z","end":"2026-05-06T00:42:53.44441Z","steps":["trace[782759526] 'process raft request' (duration: 398.917252ms)","trace[782759526] 'compare' (duration: 407.446561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:42:53.444557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:42:52.636967Z","time spent":"807.536377ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:42:53.444585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.701027ms","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-05-06T00:42:53.444637Z","caller":"traceutil/trace.go:171","msg":"trace[261641416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:289; }","duration":"477.811407ms","start":"2026-05-06T00:42:52.96681Z","end":"2026-05-06T00:42:53.444621Z","steps":["trace[261641416] 'agreement among raft nodes before linearized reading' (duration: 477.714217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:53.444865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:42:52.966779Z","time spent":"478.067018ms","remote":"127.0.0.1:33860","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-05-06T00:42:53.444749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.936695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:42:53.44501Z","caller":"traceutil/trace.go:171","msg":"trace[1567058439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:289; }","duration":"333.246227ms","start":"2026-05-06T00:42:53.111751Z","end":"2026-05-06T00:42:53.444997Z","steps":["trace[1567058439] 'agreement among raft nodes before linearized reading' (duration: 332.953775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:53.445067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:42:53.111722Z","time spent":"333.332047ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:42:53.444807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.946989ms","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-05-06T00:42:53.445163Z","caller":"traceutil/trace.go:171","msg":"trace[861299809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:289; }","duration":"321.31365ms","start":"2026-05-06T00:42:53.123834Z","end":"2026-05-06T00:42:53.445147Z","steps":["trace[861299809] 'agreement among raft nodes before linearized reading' (duration: 320.949109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:53.445243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:42:53.123822Z","time spent":"321.405251ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T00:42:53.603296Z","caller":"traceutil/trace.go:171","msg":"trace[2005473209] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"153.725743ms","start":"2026-05-06T00:42:53.449526Z","end":"2026-05-06T00:42:53.603251Z","steps":["trace[2005473209] 'process raft request' (duration: 153.566313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:53.603553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.636609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-06T00:42:53.603602Z","caller":"traceutil/trace.go:171","msg":"trace[438513566] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:292; }","duration":"152.86599ms","start":"2026-05-06T00:42:53.450725Z","end":"2026-05-06T00:42:53.603591Z","steps":["trace[438513566] 'agreement among raft nodes before linearized reading' (duration: 152.636229ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:42:53.603313Z","caller":"traceutil/trace.go:171","msg":"trace[1253872074] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:300; }","duration":"152.506898ms","start":"2026-05-06T00:42:53.450782Z","end":"2026-05-06T00:42:53.603289Z","steps":["trace[1253872074] 'read index received' (duration: 127.135471ms)","trace[1253872074] 'applied index is now lower than readState.Index' (duration: 25.370537ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:42:53.603852Z","caller":"traceutil/trace.go:171","msg":"trace[540310666] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"154.250816ms","start":"2026-05-06T00:42:53.449576Z","end":"2026-05-06T00:42:53.603827Z","steps":["trace[540310666] 'process raft request' (duration: 153.615623ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:42:53.603856Z","caller":"traceutil/trace.go:171","msg":"trace[744618179] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"154.867088ms","start":"2026-05-06T00:42:53.448974Z","end":"2026-05-06T00:42:53.603841Z","steps":["trace[744618179] 'process raft request' (duration: 128.931698ms)","trace[744618179] 'compare' (duration: 25.032706ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:42:53.754278Z","caller":"traceutil/trace.go:171","msg":"trace[600759763] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"103.871312ms","start":"2026-05-06T00:42:53.650382Z","end":"2026-05-06T00:42:53.754254Z","steps":["trace[600759763] 'process raft request' (duration: 103.449939ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:42:54.013841Z","caller":"traceutil/trace.go:171","msg":"trace[1992643112] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"139.215285ms","start":"2026-05-06T00:42:53.874599Z","end":"2026-05-06T00:42:54.013815Z","steps":["trace[1992643112] 'read index received' (duration: 120.888811ms)","trace[1992643112] 'applied index is now lower than readState.Index' (duration: 18.323624ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:42:54.013965Z","caller":"traceutil/trace.go:171","msg":"trace[1850463117] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"139.447687ms","start":"2026-05-06T00:42:53.874484Z","end":"2026-05-06T00:42:54.013932Z","steps":["trace[1850463117] 'process raft request' (duration: 121.013272ms)","trace[1850463117] 'compare' (duration: 18.210194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:42:54.014049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.424896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-06T00:42:54.014091Z","caller":"traceutil/trace.go:171","msg":"trace[1716770900] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:304; }","duration":"139.510596ms","start":"2026-05-06T00:42:53.874569Z","end":"2026-05-06T00:42:54.01408Z","steps":["trace[1716770900] 'agreement among raft nodes before linearized reading' (duration: 139.368596ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:42:54.036993Z","caller":"traceutil/trace.go:171","msg":"trace[764017306] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"146.879322ms","start":"2026-05-06T00:42:53.890098Z","end":"2026-05-06T00:42:54.036978Z","steps":["trace[764017306] 'process raft request' (duration: 146.773771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:54.037141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.440673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-06T00:42:54.037253Z","caller":"traceutil/trace.go:171","msg":"trace[1152247076] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:305; }","duration":"162.566184ms","start":"2026-05-06T00:42:53.874673Z","end":"2026-05-06T00:42:54.037239Z","steps":["trace[1152247076] 'agreement among raft nodes before linearized reading' (duration: 162.311973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:54.037372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.688132ms","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-05-06T00:42:54.037403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.687634ms","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-05-06T00:42:54.037427Z","caller":"traceutil/trace.go:171","msg":"trace[739478598] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:305; }","duration":"127.785552ms","start":"2026-05-06T00:42:53.909629Z","end":"2026-05-06T00:42:54.037414Z","steps":["trace[739478598] 'agreement among raft nodes before linearized reading' (duration: 127.641542ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:42:54.037432Z","caller":"traceutil/trace.go:171","msg":"trace[907008658] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:305; }","duration":"162.716814ms","start":"2026-05-06T00:42:53.874706Z","end":"2026-05-06T00:42:54.037422Z","steps":["trace[907008658] 'agreement among raft nodes before linearized reading' (duration: 162.656014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:54.459005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.532329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:42:54.459144Z","caller":"traceutil/trace.go:171","msg":"trace[1173915354] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"263.703605ms","start":"2026-05-06T00:42:54.195416Z","end":"2026-05-06T00:42:54.45912Z","steps":["trace[1173915354] 'process raft request' (duration: 113.959655ms)","trace[1173915354] 'compare' (duration: 149.302378ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:42:54.459515Z","caller":"traceutil/trace.go:171","msg":"trace[1067221721] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:325; }","duration":"259.983527ms","start":"2026-05-06T00:42:54.199505Z","end":"2026-05-06T00:42:54.459489Z","steps":["trace[1067221721] 'read index received' (duration: 109.867106ms)","trace[1067221721] 'applied index is now lower than readState.Index' (duration: 150.115521ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:42:54.45968Z","caller":"traceutil/trace.go:171","msg":"trace[1577884350] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"261.011343ms","start":"2026-05-06T00:42:54.198643Z","end":"2026-05-06T00:42:54.459655Z","steps":["trace[1577884350] 'process raft request' (duration: 260.661481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:54.459756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.210788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-06T00:42:54.459795Z","caller":"traceutil/trace.go:171","msg":"trace[560454521] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:316; }","duration":"260.310818ms","start":"2026-05-06T00:42:54.199475Z","end":"2026-05-06T00:42:54.459785Z","steps":["trace[560454521] 'agreement among raft nodes before linearized reading' (duration: 260.156768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:54.45985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.862398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-06T00:42:54.459905Z","caller":"traceutil/trace.go:171","msg":"trace[1145661236] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:316; }","duration":"248.981508ms","start":"2026-05-06T00:42:54.210909Z","end":"2026-05-06T00:42:54.45989Z","steps":["trace[1145661236] 'agreement among raft nodes before linearized reading' (duration: 248.860976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:54.460102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.073278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-06T00:42:54.460146Z","caller":"traceutil/trace.go:171","msg":"trace[979944574] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:316; }","duration":"199.118238ms","start":"2026-05-06T00:42:54.261014Z","end":"2026-05-06T00:42:54.460133Z","steps":["trace[979944574] 'agreement among raft nodes before linearized reading' (duration: 199.044078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:54.460124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.127068ms","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-05-06T00:42:54.460233Z","caller":"traceutil/trace.go:171","msg":"trace[168623927] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:316; }","duration":"149.300228ms","start":"2026-05-06T00:42:54.31091Z","end":"2026-05-06T00:42:54.46021Z","steps":["trace[168623927] 'agreement among raft nodes before linearized reading' (duration: 149.069957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:42:54.460551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.557164ms","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-05-06T00:42:54.460746Z","caller":"traceutil/trace.go:171","msg":"trace[500414859] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:316; }","duration":"112.052236ms","start":"2026-05-06T00:42:54.348575Z","end":"2026-05-06T00:42:54.460627Z","steps":["trace[500414859] 'agreement among raft nodes before linearized reading' (duration: 111.375583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:01.744013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.74167ms","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-05-06T00:43:01.744104Z","caller":"traceutil/trace.go:171","msg":"trace[1636186723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"101.892221ms","start":"2026-05-06T00:43:01.642192Z","end":"2026-05-06T00:43:01.744084Z","steps":["trace[1636186723] 'range keys from in-memory index tree' (duration: 101.59166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:01.744337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.315408ms","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-05-06T00:43:01.744441Z","caller":"traceutil/trace.go:171","msg":"trace[100865448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:374; }","duration":"101.482218ms","start":"2026-05-06T00:43:01.642933Z","end":"2026-05-06T00:43:01.744416Z","steps":["trace[100865448] 'range keys from in-memory index tree' (duration: 101.226677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:01.744446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.20892ms","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-05-06T00:43:01.744608Z","caller":"traceutil/trace.go:171","msg":"trace[1760294183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"106.359961ms","start":"2026-05-06T00:43:01.638185Z","end":"2026-05-06T00:43:01.744545Z","steps":["trace[1760294183] 'range keys from in-memory index tree' (duration: 105.854098ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:07.217331Z","caller":"traceutil/trace.go:171","msg":"trace[1745008971] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"272.086699ms","start":"2026-05-06T00:43:06.945216Z","end":"2026-05-06T00:43:07.217302Z","steps":["trace[1745008971] 'read index received' (duration: 271.877828ms)","trace[1745008971] 'applied index is now lower than readState.Index' (duration: 204.881µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:07.217469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.230779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:07.217512Z","caller":"traceutil/trace.go:171","msg":"trace[2133849526] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"416.920922ms","start":"2026-05-06T00:43:06.800481Z","end":"2026-05-06T00:43:07.217402Z","steps":["trace[2133849526] 'process raft request' (duration: 416.657711ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:07.217515Z","caller":"traceutil/trace.go:171","msg":"trace[1631515757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"272.28574ms","start":"2026-05-06T00:43:06.945206Z","end":"2026-05-06T00:43:07.217492Z","steps":["trace[1631515757] 'agreement among raft nodes before linearized reading' (duration: 272.207779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.217678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:06.800451Z","time spent":"417.130913ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:17.853019Z","caller":"traceutil/trace.go:171","msg":"trace[1807643096] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"136.601246ms","start":"2026-05-06T00:43:17.71638Z","end":"2026-05-06T00:43:17.852981Z","steps":["trace[1807643096] 'process raft request' (duration: 55.83187ms)","trace[1807643096] 'compare' (duration: 80.33875ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:17.853055Z","caller":"traceutil/trace.go:171","msg":"trace[1392473287] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"112.887033ms","start":"2026-05-06T00:43:17.740127Z","end":"2026-05-06T00:43:17.853014Z","steps":["trace[1392473287] 'process raft request' (duration: 112.763825ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:17.853099Z","caller":"traceutil/trace.go:171","msg":"trace[965702858] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"122.339511ms","start":"2026-05-06T00:43:17.730733Z","end":"2026-05-06T00:43:17.853072Z","steps":["trace[965702858] 'process raft request' (duration: 122.050344ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:19.138492Z","caller":"traceutil/trace.go:171","msg":"trace[625319858] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"137.973734ms","start":"2026-05-06T00:43:19.000488Z","end":"2026-05-06T00:43:19.138462Z","steps":["trace[625319858] 'read index received' (duration: 59.323343ms)","trace[625319858] 'applied index is now lower than readState.Index' (duration: 78.648841ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:19.138754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.233719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-06T00:43:19.13878Z","caller":"traceutil/trace.go:171","msg":"trace[1982426] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:552; }","duration":"138.304044ms","start":"2026-05-06T00:43:19.000466Z","end":"2026-05-06T00:43:19.13877Z","steps":["trace[1982426] 'agreement among raft nodes before linearized reading' (duration: 138.094401ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:19.139282Z","caller":"traceutil/trace.go:171","msg":"trace[461840401] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"163.704068ms","start":"2026-05-06T00:43:18.975551Z","end":"2026-05-06T00:43:19.139255Z","steps":["trace[461840401] 'process raft request' (duration: 84.252289ms)","trace[461840401] 'compare' (duration: 78.474341ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:19.159646Z","caller":"traceutil/trace.go:171","msg":"trace[362539248] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"126.343697ms","start":"2026-05-06T00:43:19.033277Z","end":"2026-05-06T00:43:19.15962Z","steps":["trace[362539248] 'process raft request' (duration: 125.444302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:20.296725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.481828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:20.29683Z","caller":"traceutil/trace.go:171","msg":"trace[1094914178] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:585; }","duration":"152.648978ms","start":"2026-05-06T00:43:20.144158Z","end":"2026-05-06T00:43:20.296807Z","steps":["trace[1094914178] 'range keys from in-memory index tree' (duration: 152.405953ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:20.688033Z","caller":"traceutil/trace.go:171","msg":"trace[471218636] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"130.883306ms","start":"2026-05-06T00:43:20.557116Z","end":"2026-05-06T00:43:20.688Z","steps":["trace[471218636] 'process raft request' (duration: 130.60734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:21.580105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.572669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:43:21.580188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.31784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-05-06T00:43:21.580218Z","caller":"traceutil/trace.go:171","msg":"trace[505361244] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:597; }","duration":"188.76995ms","start":"2026-05-06T00:43:21.391419Z","end":"2026-05-06T00:43:21.580189Z","steps":["trace[505361244] 'range keys from in-memory index tree' (duration: 188.453372ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:21.580463Z","caller":"traceutil/trace.go:171","msg":"trace[1297499938] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"227.437147ms","start":"2026-05-06T00:43:21.352797Z","end":"2026-05-06T00:43:21.580234Z","steps":["trace[1297499938] 'range keys from in-memory index tree' (duration: 227.103767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:21.829635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.750137ms","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-05-06T00:43:21.829796Z","caller":"traceutil/trace.go:171","msg":"trace[994438029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"115.946478ms","start":"2026-05-06T00:43:21.713815Z","end":"2026-05-06T00:43:21.829761Z","steps":["trace[994438029] 'range keys from in-memory index tree' (duration: 115.588788ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:22.129032Z","caller":"traceutil/trace.go:171","msg":"trace[44332418] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"125.889081ms","start":"2026-05-06T00:43:22.00309Z","end":"2026-05-06T00:43:22.128979Z","steps":["trace[44332418] 'process raft request' (duration: 104.888846ms)","trace[44332418] 'compare' (duration: 20.04385ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:22.29491Z","caller":"traceutil/trace.go:171","msg":"trace[1504334079] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"104.711177ms","start":"2026-05-06T00:43:22.190164Z","end":"2026-05-06T00:43:22.294875Z","steps":["trace[1504334079] 'process raft request' (duration: 84.076293ms)","trace[1504334079] 'compare' (duration: 20.463794ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:22.925516Z","caller":"traceutil/trace.go:171","msg":"trace[432329580] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"157.302013ms","start":"2026-05-06T00:43:22.76818Z","end":"2026-05-06T00:43:22.925482Z","steps":["trace[432329580] 'process raft request' (duration: 149.180557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:23.144265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.875647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:43:23.145105Z","caller":"traceutil/trace.go:171","msg":"trace[2144159240] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"344.247851ms","start":"2026-05-06T00:43:22.800815Z","end":"2026-05-06T00:43:23.145063Z","steps":["trace[2144159240] 'process raft request' (duration: 172.492325ms)","trace[2144159240] 'compare' (duration: 170.446333ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:23.145348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:22.800778Z","time spent":"344.416981ms","remote":"127.0.0.1:33672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:43:23.145803Z","caller":"traceutil/trace.go:171","msg":"trace[87944071] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"167.513873ms","start":"2026-05-06T00:43:22.978257Z","end":"2026-05-06T00:43:23.145771Z","steps":["trace[87944071] 'process raft request' (duration: 166.781181ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:23.21419Z","caller":"traceutil/trace.go:171","msg":"trace[1153347021] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"223.587009ms","start":"2026-05-06T00:43:22.99057Z","end":"2026-05-06T00:43:23.214157Z","steps":["trace[1153347021] 'process raft request' (duration: 223.228609ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:23.304973Z","caller":"traceutil/trace.go:171","msg":"trace[907981263] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"159.229816ms","start":"2026-05-06T00:43:23.145705Z","end":"2026-05-06T00:43:23.304935Z","steps":["trace[907981263] 'read index received' (duration: 67.949558ms)","trace[907981263] 'applied index is now lower than readState.Index' (duration: 91.278178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:23.305939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.202951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-06T00:43:23.305997Z","caller":"traceutil/trace.go:171","msg":"trace[686317419] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:614; }","duration":"160.323349ms","start":"2026-05-06T00:43:23.145658Z","end":"2026-05-06T00:43:23.305981Z","steps":["trace[686317419] 'agreement among raft nodes before linearized reading' (duration: 159.444868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:23.319456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.124229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:23.319596Z","caller":"traceutil/trace.go:171","msg":"trace[1134713629] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:615; }","duration":"162.419007ms","start":"2026-05-06T00:43:23.157158Z","end":"2026-05-06T00:43:23.319577Z","steps":["trace[1134713629] 'agreement among raft nodes before linearized reading' (duration: 162.119519ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:23.520476Z","caller":"traceutil/trace.go:171","msg":"trace[1088718474] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"109.050355ms","start":"2026-05-06T00:43:23.4114Z","end":"2026-05-06T00:43:23.52045Z","steps":["trace[1088718474] 'process raft request' (duration: 108.672514ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:28.122338Z","caller":"traceutil/trace.go:171","msg":"trace[567776074] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"482.215611ms","start":"2026-05-06T00:43:27.64008Z","end":"2026-05-06T00:43:28.122296Z","steps":["trace[567776074] 'process raft request' (duration: 482.025151ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:28.122526Z","caller":"traceutil/trace.go:171","msg":"trace[119343565] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:670; }","duration":"350.202202ms","start":"2026-05-06T00:43:27.77228Z","end":"2026-05-06T00:43:28.122482Z","steps":["trace[119343565] 'read index received' (duration: 350.187561ms)","trace[119343565] 'applied index is now lower than readState.Index' (duration: 11.411µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:28.12266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:27.64006Z","time spent":"482.490447ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:43:28.122861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.537949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r724h\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-06T00:43:28.122913Z","caller":"traceutil/trace.go:171","msg":"trace[1009231817] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r724h; range_end:; response_count:1; response_revision:648; }","duration":"350.663916ms","start":"2026-05-06T00:43:27.772237Z","end":"2026-05-06T00:43:28.1229Z","steps":["trace[1009231817] 'agreement among raft nodes before linearized reading' (duration: 350.480776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:28.122959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:27.772215Z","time spent":"350.73622ms","remote":"127.0.0.1:33804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-r724h\" "} {"level":"warn","ts":"2026-05-06T00:43:28.127998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.399243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:28.128106Z","caller":"traceutil/trace.go:171","msg":"trace[1089698512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"182.526019ms","start":"2026-05-06T00:43:27.945558Z","end":"2026-05-06T00:43:28.128084Z","steps":["trace[1089698512] 'agreement among raft nodes before linearized reading' (duration: 182.330839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:28.128093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.474507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-06T00:43:28.128155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.185547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-06T00:43:28.128299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.890387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.31\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T00:43:28.128305Z","caller":"traceutil/trace.go:171","msg":"trace[1977075095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"247.376877ms","start":"2026-05-06T00:43:27.880872Z","end":"2026-05-06T00:43:28.128248Z","steps":["trace[1977075095] 'agreement among raft nodes before linearized reading' (duration: 246.925333ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:28.128337Z","caller":"traceutil/trace.go:171","msg":"trace[798479465] range","detail":"{range_begin:/registry/masterleases/199.19.213.31; range_end:; response_count:1; response_revision:649; }","duration":"163.962001ms","start":"2026-05-06T00:43:27.964366Z","end":"2026-05-06T00:43:28.128328Z","steps":["trace[798479465] 'agreement among raft nodes before linearized reading' (duration: 163.862736ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:28.128503Z","caller":"traceutil/trace.go:171","msg":"trace[947291596] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"211.523639ms","start":"2026-05-06T00:43:27.916966Z","end":"2026-05-06T00:43:28.12849Z","steps":["trace[947291596] 'process raft request' (duration: 210.531096ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:28.128199Z","caller":"traceutil/trace.go:171","msg":"trace[1399262062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"166.652876ms","start":"2026-05-06T00:43:27.961524Z","end":"2026-05-06T00:43:28.128177Z","steps":["trace[1399262062] 'agreement among raft nodes before linearized reading' (duration: 166.33675ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:28.464365Z","caller":"traceutil/trace.go:171","msg":"trace[1636260361] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"317.772012ms","start":"2026-05-06T00:43:28.146562Z","end":"2026-05-06T00:43:28.464334Z","steps":["trace[1636260361] 'read index received' (duration: 300.033881ms)","trace[1636260361] 'applied index is now lower than readState.Index' (duration: 17.737051ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:28.464466Z","caller":"traceutil/trace.go:171","msg":"trace[2116183723] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"320.400142ms","start":"2026-05-06T00:43:28.144023Z","end":"2026-05-06T00:43:28.464424Z","steps":["trace[2116183723] 'process raft request' (duration: 302.729864ms)","trace[2116183723] 'compare' (duration: 17.379343ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:28.464606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.999114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-06T00:43:28.465013Z","caller":"traceutil/trace.go:171","msg":"trace[1467737639] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:654; }","duration":"318.48985ms","start":"2026-05-06T00:43:28.146504Z","end":"2026-05-06T00:43:28.464993Z","steps":["trace[1467737639] 'agreement among raft nodes before linearized reading' (duration: 317.936991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:28.465062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:28.146485Z","time spent":"318.564434ms","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-06T00:43:28.465071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:28.143999Z","time spent":"320.963532ms","remote":"127.0.0.1:33804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:43:28.465113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.073646ms","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-05-06T00:43:28.465209Z","caller":"traceutil/trace.go:171","msg":"trace[2117891606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"217.218603ms","start":"2026-05-06T00:43:28.247964Z","end":"2026-05-06T00:43:28.465183Z","steps":["trace[2117891606] 'agreement among raft nodes before linearized reading' (duration: 216.999742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:32.35543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.048986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:43:32.355697Z","caller":"traceutil/trace.go:171","msg":"trace[2018846578] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"201.480239ms","start":"2026-05-06T00:43:32.154193Z","end":"2026-05-06T00:43:32.355673Z","steps":["trace[2018846578] 'process raft request' (duration: 201.404356ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:32.355846Z","caller":"traceutil/trace.go:171","msg":"trace[1333762762] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"206.228638ms","start":"2026-05-06T00:43:32.149577Z","end":"2026-05-06T00:43:32.355806Z","steps":["trace[1333762762] 'process raft request' (duration: 29.661396ms)","trace[1333762762] 'compare' (duration: 175.553341ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:32.355982Z","caller":"traceutil/trace.go:171","msg":"trace[710453375] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"202.531822ms","start":"2026-05-06T00:43:32.153437Z","end":"2026-05-06T00:43:32.355969Z","steps":["trace[710453375] 'read index received' (duration: 25.814473ms)","trace[710453375] 'applied index is now lower than readState.Index' (duration: 176.716019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:32.356313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.928652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:43:32.356352Z","caller":"traceutil/trace.go:171","msg":"trace[122644001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"203.004546ms","start":"2026-05-06T00:43:32.153335Z","end":"2026-05-06T00:43:32.356339Z","steps":["trace[122644001] 'agreement among raft nodes before linearized reading' (duration: 202.767924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:32.356417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.116517ms","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-05-06T00:43:32.356464Z","caller":"traceutil/trace.go:171","msg":"trace[2029782374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:670; }","duration":"193.234213ms","start":"2026-05-06T00:43:32.163217Z","end":"2026-05-06T00:43:32.356451Z","steps":["trace[2029782374] 'agreement among raft nodes before linearized reading' (duration: 193.034823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:32.356601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.287225ms","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-05-06T00:43:32.356685Z","caller":"traceutil/trace.go:171","msg":"trace[2143965951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:670; }","duration":"192.40234ms","start":"2026-05-06T00:43:32.16426Z","end":"2026-05-06T00:43:32.356662Z","steps":["trace[2143965951] 'agreement among raft nodes before linearized reading' (duration: 192.125416ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:36.986492Z","caller":"traceutil/trace.go:171","msg":"trace[69463488] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"124.98747ms","start":"2026-05-06T00:43:36.861452Z","end":"2026-05-06T00:43:36.986439Z","steps":["trace[69463488] 'process raft request' (duration: 124.669745ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:42.580829Z","caller":"traceutil/trace.go:171","msg":"trace[1009163017] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"118.546004ms","start":"2026-05-06T00:43:42.462259Z","end":"2026-05-06T00:43:42.580805Z","steps":["trace[1009163017] 'process raft request' (duration: 118.327725ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:42.741765Z","caller":"traceutil/trace.go:171","msg":"trace[430149007] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"156.162482ms","start":"2026-05-06T00:43:42.585578Z","end":"2026-05-06T00:43:42.74174Z","steps":["trace[430149007] 'process raft request' (duration: 156.094069ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:42.74177Z","caller":"traceutil/trace.go:171","msg":"trace[296780967] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"156.426273ms","start":"2026-05-06T00:43:42.585307Z","end":"2026-05-06T00:43:42.741733Z","steps":["trace[296780967] 'process raft request' (duration: 97.821962ms)","trace[296780967] 'compare' (duration: 58.408453ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:42.742632Z","caller":"traceutil/trace.go:171","msg":"trace[746129408] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"157.0363ms","start":"2026-05-06T00:43:42.585581Z","end":"2026-05-06T00:43:42.742618Z","steps":["trace[746129408] 'process raft request' (duration: 156.126539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:48.446769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747251872,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:43:48.478225Z","caller":"traceutil/trace.go:171","msg":"trace[1463751511] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"532.156707ms","start":"2026-05-06T00:43:47.94604Z","end":"2026-05-06T00:43:48.478197Z","steps":["trace[1463751511] 'read index received' (duration: 531.949078ms)","trace[1463751511] 'applied index is now lower than readState.Index' (duration: 206.499µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:48.478346Z","caller":"traceutil/trace.go:171","msg":"trace[684900181] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"569.18905ms","start":"2026-05-06T00:43:47.909072Z","end":"2026-05-06T00:43:48.478261Z","steps":["trace[684900181] 'process raft request' (duration: 568.953761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:48.478571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.422727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:48.478658Z","caller":"traceutil/trace.go:171","msg":"trace[47902015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"532.673158ms","start":"2026-05-06T00:43:47.945967Z","end":"2026-05-06T00:43:48.47864Z","steps":["trace[47902015] 'agreement among raft nodes before linearized reading' (duration: 532.437368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:48.478694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:47.909055Z","time spent":"569.460782ms","remote":"127.0.0.1:33804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:43:48.478719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:47.94594Z","time spent":"532.764942ms","remote":"127.0.0.1:33612","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-05-06T00:43:48.478709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.228291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.31\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T00:43:48.478896Z","caller":"traceutil/trace.go:171","msg":"trace[1251471533] range","detail":"{range_begin:/registry/masterleases/199.19.213.31; range_end:; response_count:1; response_revision:773; }","duration":"513.398608ms","start":"2026-05-06T00:43:47.965436Z","end":"2026-05-06T00:43:48.478835Z","steps":["trace[1251471533] 'agreement among raft nodes before linearized reading' (duration: 513.032963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:48.478946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:47.965425Z","time spent":"513.509013ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.31\" "} {"level":"info","ts":"2026-05-06T00:43:54.908047Z","caller":"traceutil/trace.go:171","msg":"trace[1280200087] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"104.392164ms","start":"2026-05-06T00:43:54.803626Z","end":"2026-05-06T00:43:54.908019Z","steps":["trace[1280200087] 'process raft request' (duration: 101.105297ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:54.908289Z","caller":"traceutil/trace.go:171","msg":"trace[1535380958] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"102.685257ms","start":"2026-05-06T00:43:54.805579Z","end":"2026-05-06T00:43:54.908264Z","steps":["trace[1535380958] 'process raft request' (duration: 102.391106ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:59.804837Z","caller":"traceutil/trace.go:171","msg":"trace[1339797265] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"148.203266ms","start":"2026-05-06T00:43:59.6566Z","end":"2026-05-06T00:43:59.804803Z","steps":["trace[1339797265] 'process raft request' (duration: 147.48293ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:00.102502Z","caller":"traceutil/trace.go:171","msg":"trace[1940384139] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"145.948613ms","start":"2026-05-06T00:43:59.956528Z","end":"2026-05-06T00:44:00.102477Z","steps":["trace[1940384139] 'process raft request' (duration: 95.302557ms)","trace[1940384139] 'compare' (duration: 50.508341ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:44:00.105264Z","caller":"traceutil/trace.go:171","msg":"trace[843844342] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"145.37815ms","start":"2026-05-06T00:43:59.959872Z","end":"2026-05-06T00:44:00.10525Z","steps":["trace[843844342] 'process raft request' (duration: 145.301757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.728778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747252136,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:44:00.748813Z","caller":"traceutil/trace.go:171","msg":"trace[2135563649] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"572.680691ms","start":"2026-05-06T00:44:00.176106Z","end":"2026-05-06T00:44:00.748787Z","steps":["trace[2135563649] 'process raft request' (duration: 572.609699ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:00.74895Z","caller":"traceutil/trace.go:171","msg":"trace[844650958] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"573.640736ms","start":"2026-05-06T00:44:00.175267Z","end":"2026-05-06T00:44:00.748908Z","steps":["trace[844650958] 'process raft request' (duration: 573.371556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.749037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:00.176074Z","time spent":"572.834097ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:44:00.749143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:00.17524Z","time spent":"573.802542ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3204,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:44:00.749315Z","caller":"traceutil/trace.go:171","msg":"trace[1190779179] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"574.688954ms","start":"2026-05-06T00:44:00.174595Z","end":"2026-05-06T00:44:00.749284Z","steps":["trace[1190779179] 'process raft request' (duration: 573.811513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.749476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:00.174586Z","time spent":"574.788608ms","remote":"127.0.0.1:33804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2635,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:44:00.757107Z","caller":"traceutil/trace.go:171","msg":"trace[1733969079] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:944; }","duration":"528.946775ms","start":"2026-05-06T00:44:00.228143Z","end":"2026-05-06T00:44:00.75709Z","steps":["trace[1733969079] 'read index received' (duration: 520.295163ms)","trace[1733969079] 'applied index is now lower than readState.Index' (duration: 8.650732ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:44:00.757137Z","caller":"traceutil/trace.go:171","msg":"trace[479117511] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"163.171753ms","start":"2026-05-06T00:44:00.593934Z","end":"2026-05-06T00:44:00.757106Z","steps":["trace[479117511] 'process raft request' (duration: 163.005558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.757315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.163682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-05-06T00:44:00.757358Z","caller":"traceutil/trace.go:171","msg":"trace[1445211186] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:917; }","duration":"529.249636ms","start":"2026-05-06T00:44:00.228097Z","end":"2026-05-06T00:44:00.757346Z","steps":["trace[1445211186] 'agreement among raft nodes before linearized reading' (duration: 529.0814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.75739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:00.228078Z","time spent":"529.305067ms","remote":"127.0.0.1:33712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12778,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-05-06T00:44:01.063206Z","caller":"traceutil/trace.go:171","msg":"trace[1023603041] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:950; }","duration":"281.546409ms","start":"2026-05-06T00:44:00.781636Z","end":"2026-05-06T00:44:01.063182Z","steps":["trace[1023603041] 'read index received' (duration: 280.891095ms)","trace[1023603041] 'applied index is now lower than readState.Index' (duration: 653.924µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:44:01.063237Z","caller":"traceutil/trace.go:171","msg":"trace[1570758447] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"299.848889ms","start":"2026-05-06T00:44:00.763366Z","end":"2026-05-06T00:44:01.063215Z","steps":["trace[1570758447] 'process raft request' (duration: 299.183584ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:01.063323Z","caller":"traceutil/trace.go:171","msg":"trace[1917400109] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"298.248731ms","start":"2026-05-06T00:44:00.765066Z","end":"2026-05-06T00:44:01.063314Z","steps":["trace[1917400109] 'process raft request' (duration: 298.049794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:01.063561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.864218ms","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-05-06T00:44:01.063642Z","caller":"traceutil/trace.go:171","msg":"trace[1490254215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"208.976402ms","start":"2026-05-06T00:44:00.854644Z","end":"2026-05-06T00:44:01.06362Z","steps":["trace[1490254215] 'agreement among raft nodes before linearized reading' (duration: 208.782125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:01.063795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.567248ms","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-05-06T00:44:01.063833Z","caller":"traceutil/trace.go:171","msg":"trace[697310197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"124.650381ms","start":"2026-05-06T00:44:00.939172Z","end":"2026-05-06T00:44:01.063822Z","steps":["trace[697310197] 'agreement among raft nodes before linearized reading' (duration: 124.504706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:01.063893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.681627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-05-06T00:44:01.063936Z","caller":"traceutil/trace.go:171","msg":"trace[523140076] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:921; }","duration":"125.73441ms","start":"2026-05-06T00:44:00.93819Z","end":"2026-05-06T00:44:01.063924Z","steps":["trace[523140076] 'agreement among raft nodes before linearized reading' (duration: 125.621316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:01.063969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.327017ms","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-05-06T00:44:01.063998Z","caller":"traceutil/trace.go:171","msg":"trace[1708377002] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:921; }","duration":"282.483072ms","start":"2026-05-06T00:44:00.781506Z","end":"2026-05-06T00:44:01.06399Z","steps":["trace[1708377002] 'agreement among raft nodes before linearized reading' (duration: 282.43069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:01.06417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.167465ms","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-05-06T00:44:01.064209Z","caller":"traceutil/trace.go:171","msg":"trace[1367512565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"131.217478ms","start":"2026-05-06T00:44:00.932983Z","end":"2026-05-06T00:44:01.0642Z","steps":["trace[1367512565] 'agreement among raft nodes before linearized reading' (duration: 131.122894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:01.064377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.589491ms","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-05-06T00:44:01.064407Z","caller":"traceutil/trace.go:171","msg":"trace[1445372204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"131.650193ms","start":"2026-05-06T00:44:00.93275Z","end":"2026-05-06T00:44:01.0644Z","steps":["trace[1445372204] 'agreement among raft nodes before linearized reading' (duration: 131.58272ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:33.569835Z","caller":"traceutil/trace.go:171","msg":"trace[734494730] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"499.217281ms","start":"2026-05-06T00:44:33.070589Z","end":"2026-05-06T00:44:33.569806Z","steps":["trace[734494730] 'process raft request' (duration: 499.060617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:33.570015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:33.070576Z","time spent":"499.350035ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:44:33.570072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.285634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:44:33.570194Z","caller":"traceutil/trace.go:171","msg":"trace[710394] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1052; }","duration":"434.49899ms","start":"2026-05-06T00:44:33.135671Z","end":"2026-05-06T00:44:33.57017Z","steps":["trace[710394] 'agreement among raft nodes before linearized reading' (duration: 434.275124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:33.570265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:33.135653Z","time spent":"434.595032ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:44:33.570534Z","caller":"traceutil/trace.go:171","msg":"trace[25235903] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1091; }","duration":"434.10675ms","start":"2026-05-06T00:44:33.135731Z","end":"2026-05-06T00:44:33.569837Z","steps":["trace[25235903] 'read index received' (duration: 434.08671ms)","trace[25235903] 'applied index is now lower than readState.Index' (duration: 17.15µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:44:33.611331Z","caller":"traceutil/trace.go:171","msg":"trace[1709330717] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"242.304954ms","start":"2026-05-06T00:44:33.368997Z","end":"2026-05-06T00:44:33.611302Z","steps":["trace[1709330717] 'process raft request' (duration: 242.045698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:33.615108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.46046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-06T00:44:33.615144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.180502ms","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-05-06T00:44:33.615439Z","caller":"traceutil/trace.go:171","msg":"trace[1360508579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"215.540562ms","start":"2026-05-06T00:44:33.399876Z","end":"2026-05-06T00:44:33.615417Z","steps":["trace[1360508579] 'agreement among raft nodes before linearized reading' (duration: 211.471557ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:33.615273Z","caller":"traceutil/trace.go:171","msg":"trace[1069184185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"380.630534ms","start":"2026-05-06T00:44:33.234614Z","end":"2026-05-06T00:44:33.615245Z","steps":["trace[1069184185] 'agreement among raft nodes before linearized reading' (duration: 376.764106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:33.615647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:33.234543Z","time spent":"381.082687ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T00:44:33.615158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.778081ms","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-05-06T00:44:33.615909Z","caller":"traceutil/trace.go:171","msg":"trace[1795201478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"204.541331ms","start":"2026-05-06T00:44:33.411347Z","end":"2026-05-06T00:44:33.615889Z","steps":["trace[1795201478] 'agreement among raft nodes before linearized reading' (duration: 199.883112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:33.615159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.669563ms","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-05-06T00:44:33.616044Z","caller":"traceutil/trace.go:171","msg":"trace[336084106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1053; }","duration":"194.591288ms","start":"2026-05-06T00:44:33.421431Z","end":"2026-05-06T00:44:33.616022Z","steps":["trace[336084106] 'agreement among raft nodes before linearized reading' (duration: 189.9727ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:50.056915Z","caller":"traceutil/trace.go:171","msg":"trace[1237848150] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"398.860957ms","start":"2026-05-06T00:44:49.658021Z","end":"2026-05-06T00:44:50.056882Z","steps":["trace[1237848150] 'read index received' (duration: 398.666913ms)","trace[1237848150] 'applied index is now lower than readState.Index' (duration: 193.304µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:44:50.057364Z","caller":"traceutil/trace.go:171","msg":"trace[129462530] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"408.617573ms","start":"2026-05-06T00:44:49.648729Z","end":"2026-05-06T00:44:50.057346Z","steps":["trace[129462530] 'process raft request' (duration: 408.030701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.057497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:49.648703Z","time spent":"408.705685ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:44:50.057832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.804499ms","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-05-06T00:44:50.057873Z","caller":"traceutil/trace.go:171","msg":"trace[433025834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"399.90584ms","start":"2026-05-06T00:44:49.657955Z","end":"2026-05-06T00:44:50.057861Z","steps":["trace[433025834] 'agreement among raft nodes before linearized reading' (duration: 399.781908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.057911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:49.657939Z","time spent":"399.962221ms","remote":"127.0.0.1:33860","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-05-06T00:44:50.058051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.377213ms","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-05-06T00:44:50.058078Z","caller":"traceutil/trace.go:171","msg":"trace[1127108255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"334.425274ms","start":"2026-05-06T00:44:49.723644Z","end":"2026-05-06T00:44:50.05807Z","steps":["trace[1127108255] 'agreement among raft nodes before linearized reading' (duration: 334.349192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.058105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:49.723633Z","time spent":"334.464094ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T00:44:50.058303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.31866ms","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-05-06T00:44:50.058417Z","caller":"traceutil/trace.go:171","msg":"trace[2105160256] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1104; }","duration":"333.465423ms","start":"2026-05-06T00:44:49.724932Z","end":"2026-05-06T00:44:50.058398Z","steps":["trace[2105160256] 'agreement among raft nodes before linearized reading' (duration: 333.305809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.058463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:49.724921Z","time spent":"333.531474ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:44:50.058714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.567453ms","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":"info","ts":"2026-05-06T00:44:50.058752Z","caller":"traceutil/trace.go:171","msg":"trace[1039817292] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1104; }","duration":"106.607353ms","start":"2026-05-06T00:44:49.952135Z","end":"2026-05-06T00:44:50.058743Z","steps":["trace[1039817292] 'agreement among raft nodes before linearized reading' (duration: 106.495831ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:50.571167Z","caller":"traceutil/trace.go:171","msg":"trace[2049483593] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"509.165917ms","start":"2026-05-06T00:44:50.061971Z","end":"2026-05-06T00:44:50.571137Z","steps":["trace[2049483593] 'process raft request' (duration: 507.575923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.571339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:50.061952Z","time spent":"509.28993ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:44:50.571632Z","caller":"traceutil/trace.go:171","msg":"trace[954275495] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1147; }","duration":"165.871341ms","start":"2026-05-06T00:44:50.405733Z","end":"2026-05-06T00:44:50.571604Z","steps":["trace[954275495] 'read index received' (duration: 163.827747ms)","trace[954275495] 'applied index is now lower than readState.Index' (duration: 2.042484ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:44:50.571706Z","caller":"traceutil/trace.go:171","msg":"trace[1401104684] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"494.379102ms","start":"2026-05-06T00:44:50.077313Z","end":"2026-05-06T00:44:50.571692Z","steps":["trace[1401104684] 'process raft request' (duration: 494.073866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.571777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:50.077299Z","time spent":"494.444674ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:44:50.571942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.197198ms","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-05-06T00:44:50.571988Z","caller":"traceutil/trace.go:171","msg":"trace[2136031699] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1107; }","duration":"166.28394ms","start":"2026-05-06T00:44:50.405688Z","end":"2026-05-06T00:44:50.571972Z","steps":["trace[2136031699] 'agreement among raft nodes before linearized reading' (duration: 166.201439ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:02.716366Z","caller":"traceutil/trace.go:171","msg":"trace[1521880729] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"101.71734ms","start":"2026-05-06T00:45:02.614618Z","end":"2026-05-06T00:45:02.716336Z","steps":["trace[1521880729] 'process raft request' (duration: 101.527396ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:02.827348Z","caller":"traceutil/trace.go:171","msg":"trace[1186253071] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"105.836982ms","start":"2026-05-06T00:45:02.721477Z","end":"2026-05-06T00:45:02.827314Z","steps":["trace[1186253071] 'process raft request' (duration: 71.421599ms)","trace[1186253071] 'compare' (duration: 34.26132ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:02.827852Z","caller":"traceutil/trace.go:171","msg":"trace[1872471400] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"106.007054ms","start":"2026-05-06T00:45:02.721832Z","end":"2026-05-06T00:45:02.827839Z","steps":["trace[1872471400] 'process raft request' (duration: 105.869612ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:02.827862Z","caller":"traceutil/trace.go:171","msg":"trace[1031103510] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"105.963303ms","start":"2026-05-06T00:45:02.72188Z","end":"2026-05-06T00:45:02.827843Z","steps":["trace[1031103510] 'process raft request' (duration: 105.937243ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:02.827977Z","caller":"traceutil/trace.go:171","msg":"trace[539765539] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"106.091006ms","start":"2026-05-06T00:45:02.72188Z","end":"2026-05-06T00:45:02.827971Z","steps":["trace[539765539] 'process raft request' (duration: 105.916392ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:02.828036Z","caller":"traceutil/trace.go:171","msg":"trace[2001146962] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"106.160607ms","start":"2026-05-06T00:45:02.72184Z","end":"2026-05-06T00:45:02.828001Z","steps":["trace[2001146962] 'process raft request' (duration: 105.933923ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:03.135353Z","caller":"traceutil/trace.go:171","msg":"trace[1746215579] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"126.880369ms","start":"2026-05-06T00:45:03.008448Z","end":"2026-05-06T00:45:03.135328Z","steps":["trace[1746215579] 'process raft request' (duration: 126.647075ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:05.010849Z","caller":"traceutil/trace.go:171","msg":"trace[1456634047] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"135.054441ms","start":"2026-05-06T00:45:04.875764Z","end":"2026-05-06T00:45:05.010818Z","steps":["trace[1456634047] 'process raft request' (duration: 130.852599ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:05.010855Z","caller":"traceutil/trace.go:171","msg":"trace[1297617552] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"134.191743ms","start":"2026-05-06T00:45:04.87664Z","end":"2026-05-06T00:45:05.010831Z","steps":["trace[1297617552] 'process raft request' (duration: 133.919228ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:05.010878Z","caller":"traceutil/trace.go:171","msg":"trace[2031605781] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1197; }","duration":"115.307924ms","start":"2026-05-06T00:45:04.89553Z","end":"2026-05-06T00:45:05.010838Z","steps":["trace[2031605781] 'read index received' (duration: 22.796206ms)","trace[2031605781] 'applied index is now lower than readState.Index' (duration: 92.510128ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:05.01095Z","caller":"traceutil/trace.go:171","msg":"trace[137040828] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"133.201424ms","start":"2026-05-06T00:45:04.877733Z","end":"2026-05-06T00:45:05.010935Z","steps":["trace[137040828] 'process raft request' (duration: 133.037671ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:05.010939Z","caller":"traceutil/trace.go:171","msg":"trace[275700359] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"133.705603ms","start":"2026-05-06T00:45:04.877191Z","end":"2026-05-06T00:45:05.010897Z","steps":["trace[275700359] 'process raft request' (duration: 133.52733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:05.011524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.858754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:45:05.011776Z","caller":"traceutil/trace.go:171","msg":"trace[2146327453] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1156; }","duration":"116.252471ms","start":"2026-05-06T00:45:04.895499Z","end":"2026-05-06T00:45:05.011752Z","steps":["trace[2146327453] 'agreement among raft nodes before linearized reading' (duration: 115.737152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:07.330918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.344026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:45:07.331077Z","caller":"traceutil/trace.go:171","msg":"trace[2005838052] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"298.830872ms","start":"2026-05-06T00:45:07.032219Z","end":"2026-05-06T00:45:07.33105Z","steps":["trace[2005838052] 'process raft request' (duration: 72.14866ms)","trace[2005838052] 'compare' (duration: 225.875847ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:07.33765Z","caller":"traceutil/trace.go:171","msg":"trace[646528919] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"304.716154ms","start":"2026-05-06T00:45:07.032921Z","end":"2026-05-06T00:45:07.337637Z","steps":["trace[646528919] 'process raft request' (duration: 304.644132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:07.337727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:07.032886Z","time spent":"304.805565ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:07.337712Z","caller":"traceutil/trace.go:171","msg":"trace[1793683066] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"304.889357ms","start":"2026-05-06T00:45:07.032783Z","end":"2026-05-06T00:45:07.337672Z","steps":["trace[1793683066] 'process raft request' (duration: 304.665813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:07.33794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:07.032767Z","time spent":"305.043381ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:07.33807Z","caller":"traceutil/trace.go:171","msg":"trace[774953726] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"303.953959ms","start":"2026-05-06T00:45:07.034104Z","end":"2026-05-06T00:45:07.338058Z","steps":["trace[774953726] 'process raft request' (duration: 303.49871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:07.338156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:07.034081Z","time spent":"304.041261ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:07.338296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.400977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:07.33845Z","caller":"traceutil/trace.go:171","msg":"trace[870465734] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1162; }","duration":"204.6ms","start":"2026-05-06T00:45:07.133808Z","end":"2026-05-06T00:45:07.338408Z","steps":["trace[870465734] 'agreement among raft nodes before linearized reading' (duration: 204.397616ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:07.337915Z","caller":"traceutil/trace.go:171","msg":"trace[1471344006] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1205; }","duration":"204.016579ms","start":"2026-05-06T00:45:07.133855Z","end":"2026-05-06T00:45:07.337872Z","steps":["trace[1471344006] 'read index received' (duration: 203.568611ms)","trace[1471344006] 'applied index is now lower than readState.Index' (duration: 444.758µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:07.338837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.152331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5547"} {"level":"info","ts":"2026-05-06T00:45:07.338871Z","caller":"traceutil/trace.go:171","msg":"trace[410136127] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1162; }","duration":"161.217192ms","start":"2026-05-06T00:45:07.177642Z","end":"2026-05-06T00:45:07.338859Z","steps":["trace[410136127] 'agreement among raft nodes before linearized reading' (duration: 161.069939ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:15.038767Z","caller":"traceutil/trace.go:171","msg":"trace[1924472723] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"177.254087ms","start":"2026-05-06T00:45:14.861485Z","end":"2026-05-06T00:45:15.038739Z","steps":["trace[1924472723] 'process raft request' (duration: 177.113165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:18.467962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.0566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:45:18.468057Z","caller":"traceutil/trace.go:171","msg":"trace[585469943] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1196; }","duration":"168.224544ms","start":"2026-05-06T00:45:18.299811Z","end":"2026-05-06T00:45:18.468035Z","steps":["trace[585469943] 'count revisions from in-memory index tree' (duration: 167.962609ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:33.800766Z","caller":"traceutil/trace.go:171","msg":"trace[1566473588] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"106.453251ms","start":"2026-05-06T00:45:33.694277Z","end":"2026-05-06T00:45:33.80073Z","steps":["trace[1566473588] 'process raft request' (duration: 105.907113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:42.899778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253145,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:45:42.903917Z","caller":"traceutil/trace.go:171","msg":"trace[284087533] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"582.707912ms","start":"2026-05-06T00:45:42.321173Z","end":"2026-05-06T00:45:42.90388Z","steps":["trace[284087533] 'read index received' (duration: 582.52198ms)","trace[284087533] 'applied index is now lower than readState.Index' (duration: 185.272µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:42.904172Z","caller":"traceutil/trace.go:171","msg":"trace[814504723] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"846.457092ms","start":"2026-05-06T00:45:42.057693Z","end":"2026-05-06T00:45:42.90415Z","steps":["trace[814504723] 'process raft request' (duration: 846.058847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:42.904202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.015047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"warn","ts":"2026-05-06T00:45:42.904326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:42.057661Z","time spent":"846.586745ms","remote":"127.0.0.1:33804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2565,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:42.904277Z","caller":"traceutil/trace.go:171","msg":"trace[803384815] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1312; }","duration":"583.14571ms","start":"2026-05-06T00:45:42.321115Z","end":"2026-05-06T00:45:42.904261Z","steps":["trace[803384815] 'agreement among raft nodes before linearized reading' (duration: 582.922106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:42.904811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:42.321092Z","time spent":"583.686248ms","remote":"127.0.0.1:33786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1488,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-05-06T00:45:44.444517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:44.94557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:45.446388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:45.944013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000144897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T00:45:45.944114Z","caller":"traceutil/trace.go:171","msg":"trace[710813453] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000318729s","start":"2026-05-06T00:45:43.943776Z","end":"2026-05-06T00:45:45.944095Z","steps":["trace[710813453] 'agreement among raft nodes before linearized reading' (duration: 2.000192367s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:45.944578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:43.943745Z","time spent":"2.000440891s","remote":"127.0.0.1:33612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 00:45:45 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T00:45:45.947271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:46.448287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:46.949034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:47.449406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:47.944222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99981458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T00:45:47.944311Z","caller":"traceutil/trace.go:171","msg":"trace[919771282] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999975252s","start":"2026-05-06T00:45:45.944319Z","end":"2026-05-06T00:45:47.944294Z","steps":["trace[919771282] 'agreement among raft nodes before linearized reading' (duration: 1.99984389s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:47.944356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.944292Z","time spent":"2.000053734s","remote":"127.0.0.1:33624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:45:47.94978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:48.450825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:48.735419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:43.737828Z","time spent":"4.997587025s","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-06T00:45:48.735366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:43.736823Z","time spent":"4.998535669s","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/06 00:45:48 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T00:45:48.951072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961774008747253161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:49.25402Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.517086948s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T00:45:49.33185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.751742ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:49.331978Z","caller":"traceutil/trace.go:171","msg":"trace[2126000217] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1317; }","duration":"513.893854ms","start":"2026-05-06T00:45:48.818065Z","end":"2026-05-06T00:45:49.331959Z","steps":["trace[2126000217] 'range keys from in-memory index tree' (duration: 513.735842ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:49.342157Z","caller":"traceutil/trace.go:171","msg":"trace[1628115624] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1372; }","duration":"5.398293513s","start":"2026-05-06T00:45:43.943847Z","end":"2026-05-06T00:45:49.34214Z","steps":["trace[1628115624] 'read index received' (duration: 5.310353236s)","trace[1628115624] 'applied index is now lower than readState.Index' (duration: 87.939377ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:49.342262Z","caller":"traceutil/trace.go:171","msg":"trace[117735077] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"4.12788385s","start":"2026-05-06T00:45:45.214362Z","end":"2026-05-06T00:45:49.342246Z","steps":["trace[117735077] 'process raft request' (duration: 4.127718948s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:49.342253Z","caller":"traceutil/trace.go:171","msg":"trace[364493760] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"5.397606843s","start":"2026-05-06T00:45:43.944597Z","end":"2026-05-06T00:45:49.342204Z","steps":["trace[364493760] 'process raft request' (duration: 5.397455901s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.342406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450759816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T00:45:49.342425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.004490955s","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-05-06T00:45:49.342447Z","caller":"traceutil/trace.go:171","msg":"trace[982115055] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1322; }","duration":"1.450867108s","start":"2026-05-06T00:45:47.891571Z","end":"2026-05-06T00:45:49.342438Z","steps":["trace[982115055] 'agreement among raft nodes before linearized reading' (duration: 1.450769847s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:49.342465Z","caller":"traceutil/trace.go:171","msg":"trace[1926931456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"4.004563985s","start":"2026-05-06T00:45:45.337888Z","end":"2026-05-06T00:45:49.342452Z","steps":["trace[1926931456] 'agreement among raft nodes before linearized reading' (duration: 4.004401973s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.342475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:47.891546Z","time spent":"1.450922369s","remote":"127.0.0.1:34014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:45:49.342493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.337868Z","time spent":"4.004617337s","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T00:45:49.342529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.214304Z","time spent":"4.127995562s","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:49.342567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:43.944578Z","time spent":"5.397832866s","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:49.342583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.008474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:45:49.342606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.954096546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:45:49.342633Z","caller":"traceutil/trace.go:171","msg":"trace[804598722] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1322; }","duration":"503.071785ms","start":"2026-05-06T00:45:48.839549Z","end":"2026-05-06T00:45:49.342621Z","steps":["trace[804598722] 'agreement among raft nodes before linearized reading' (duration: 502.969583ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:49.342641Z","caller":"traceutil/trace.go:171","msg":"trace[2102436586] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1322; }","duration":"3.954163508s","start":"2026-05-06T00:45:45.388467Z","end":"2026-05-06T00:45:49.34263Z","steps":["trace[2102436586] 'agreement among raft nodes before linearized reading' (duration: 3.954095927s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.342656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369231653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.31\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-06T00:45:49.342669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:48.839501Z","time spent":"503.160015ms","remote":"127.0.0.1:33818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:45:49.342674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.388455Z","time spent":"3.954208998s","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":5,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:45:49.342693Z","caller":"traceutil/trace.go:171","msg":"trace[1396885886] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"1.393780889s","start":"2026-05-06T00:45:47.9489Z","end":"2026-05-06T00:45:49.342681Z","steps":["trace[1396885886] 'process raft request' (duration: 1.39320808s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.342707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389775479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:49.342725Z","caller":"traceutil/trace.go:171","msg":"trace[1920343204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"1.389819189s","start":"2026-05-06T00:45:47.9529Z","end":"2026-05-06T00:45:49.342719Z","steps":["trace[1920343204] 'agreement among raft nodes before linearized reading' (duration: 1.389792089s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:49.342678Z","caller":"traceutil/trace.go:171","msg":"trace[524106297] range","detail":"{range_begin:/registry/masterleases/199.19.213.31; range_end:; response_count:1; response_revision:1322; }","duration":"1.369276073s","start":"2026-05-06T00:45:47.973395Z","end":"2026-05-06T00:45:49.342671Z","steps":["trace[524106297] 'agreement among raft nodes before linearized reading' (duration: 1.369211473s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.342744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:47.952889Z","time spent":"1.389849079s","remote":"127.0.0.1:33612","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-05-06T00:45:49.342744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:47.948876Z","time spent":"1.393842339s","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:45:49.342744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.625951583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-06T00:45:49.342781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:47.973384Z","time spent":"1.369372705s","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.31\" "} {"level":"info","ts":"2026-05-06T00:45:49.342849Z","caller":"traceutil/trace.go:171","msg":"trace[473477543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"3.626108954s","start":"2026-05-06T00:45:45.716701Z","end":"2026-05-06T00:45:49.34281Z","steps":["trace[473477543] 'agreement among raft nodes before linearized reading' (duration: 3.62580984s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.342895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.71668Z","time spent":"3.626206807s","remote":"127.0.0.1:33860","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-05-06T00:45:49.343043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.257848143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-06T00:45:49.343079Z","caller":"traceutil/trace.go:171","msg":"trace[566777518] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1322; }","duration":"3.257930314s","start":"2026-05-06T00:45:46.085137Z","end":"2026-05-06T00:45:49.343068Z","steps":["trace[566777518] 'agreement among raft nodes before linearized reading' (duration: 3.257862753s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.343104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.26096687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:50493"} {"level":"warn","ts":"2026-05-06T00:45:49.343121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.085116Z","time spent":"3.257983475s","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-06T00:45:49.343133Z","caller":"traceutil/trace.go:171","msg":"trace[850555330] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1322; }","duration":"3.26103119s","start":"2026-05-06T00:45:46.082094Z","end":"2026-05-06T00:45:49.343125Z","steps":["trace[850555330] 'agreement among raft nodes before linearized reading' (duration: 3.260911858s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.343168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.082077Z","time spent":"3.261085581s","remote":"127.0.0.1:33674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":50516,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-06T00:45:49.343421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.607589695s","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-05-06T00:45:49.343494Z","caller":"traceutil/trace.go:171","msg":"trace[278018338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"3.607670867s","start":"2026-05-06T00:45:45.735788Z","end":"2026-05-06T00:45:49.343459Z","steps":["trace[278018338] 'agreement among raft nodes before linearized reading' (duration: 3.606604652s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.343525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.735762Z","time spent":"3.607756008s","remote":"127.0.0.1:33860","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-05-06T00:45:49.683031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.12125ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-06T00:45:49.683179Z","caller":"traceutil/trace.go:171","msg":"trace[1327692668] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"337.871857ms","start":"2026-05-06T00:45:49.345282Z","end":"2026-05-06T00:45:49.683154Z","steps":["trace[1327692668] 'read index received' (duration: 128.662186ms)","trace[1327692668] 'applied index is now lower than readState.Index' (duration: 209.207361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:49.683389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:49.343995Z","time spent":"339.373821ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-06T00:45:49.683656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.351215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:3 size:3811"} {"level":"info","ts":"2026-05-06T00:45:49.683711Z","caller":"traceutil/trace.go:171","msg":"trace[189495471] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:3; response_revision:1322; }","duration":"338.506067ms","start":"2026-05-06T00:45:49.345192Z","end":"2026-05-06T00:45:49.683698Z","steps":["trace[189495471] 'agreement among raft nodes before linearized reading' (duration: 338.238832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.683766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:49.345173Z","time spent":"338.582119ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":3,"response size":3834,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-05-06T00:45:49.854027Z","caller":"traceutil/trace.go:171","msg":"trace[998647892] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1378; }","duration":"170.795447ms","start":"2026-05-06T00:45:49.683213Z","end":"2026-05-06T00:45:49.854008Z","steps":["trace[998647892] 'read index received' (duration: 167.689731ms)","trace[998647892] 'applied index is now lower than readState.Index' (duration: 3.105176ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:49.854075Z","caller":"traceutil/trace.go:171","msg":"trace[504810067] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"506.511212ms","start":"2026-05-06T00:45:49.347532Z","end":"2026-05-06T00:45:49.854044Z","steps":["trace[504810067] 'process raft request' (duration: 506.422741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.854237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.309831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:45:49.85426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.573903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:3 size:3811"} {"level":"info","ts":"2026-05-06T00:45:49.854119Z","caller":"traceutil/trace.go:171","msg":"trace[1638644549] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"506.536452ms","start":"2026-05-06T00:45:49.347575Z","end":"2026-05-06T00:45:49.854111Z","steps":["trace[1638644549] 'process raft request' (duration: 506.408651ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:49.854296Z","caller":"traceutil/trace.go:171","msg":"trace[895663061] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:3; response_revision:1325; }","duration":"508.644764ms","start":"2026-05-06T00:45:49.345643Z","end":"2026-05-06T00:45:49.854288Z","steps":["trace[895663061] 'agreement among raft nodes before linearized reading' (duration: 508.485352ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:49.854302Z","caller":"traceutil/trace.go:171","msg":"trace[618065216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"502.435613ms","start":"2026-05-06T00:45:49.35185Z","end":"2026-05-06T00:45:49.854285Z","steps":["trace[618065216] 'agreement among raft nodes before linearized reading' (duration: 502.282471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.854346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:49.345631Z","time spent":"508.706005ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":3,"response size":3834,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-05-06T00:45:49.85438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:49.351842Z","time spent":"502.529444ms","remote":"127.0.0.1:33612","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-05-06T00:45:49.854392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:49.347555Z","time spent":"506.749986ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:49.854247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:49.347513Z","time spent":"506.638434ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:49.854559Z","caller":"traceutil/trace.go:171","msg":"trace[760499166] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"507.068211ms","start":"2026-05-06T00:45:49.347447Z","end":"2026-05-06T00:45:49.854515Z","steps":["trace[760499166] 'process raft request' (duration: 503.365186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.85494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:49.347434Z","time spent":"507.277134ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:49.85829Z","caller":"traceutil/trace.go:171","msg":"trace[1012817940] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"173.297213ms","start":"2026-05-06T00:45:49.684975Z","end":"2026-05-06T00:45:49.858272Z","steps":["trace[1012817940] 'process raft request' (duration: 173.143431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.858429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.420657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T00:45:49.858336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.473742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-06T00:45:49.858532Z","caller":"traceutil/trace.go:171","msg":"trace[883351505] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1326; }","duration":"169.559399ms","start":"2026-05-06T00:45:49.688948Z","end":"2026-05-06T00:45:49.858508Z","steps":["trace[883351505] 'agreement among raft nodes before linearized reading' (duration: 169.347646ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:49.858538Z","caller":"traceutil/trace.go:171","msg":"trace[1931515400] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1326; }","duration":"172.717205ms","start":"2026-05-06T00:45:49.685802Z","end":"2026-05-06T00:45:49.85852Z","steps":["trace[1931515400] 'agreement among raft nodes before linearized reading' (duration: 172.435381ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:55.512814Z","caller":"traceutil/trace.go:171","msg":"trace[2014549925] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"110.308348ms","start":"2026-05-06T00:45:55.402483Z","end":"2026-05-06T00:45:55.512791Z","steps":["trace[2014549925] 'read index received' (duration: 110.093265ms)","trace[2014549925] 'applied index is now lower than readState.Index' (duration: 214.113µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:55.512885Z","caller":"traceutil/trace.go:171","msg":"trace[197139431] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"152.989279ms","start":"2026-05-06T00:45:55.359871Z","end":"2026-05-06T00:45:55.51286Z","steps":["trace[197139431] 'process raft request' (duration: 152.781196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:55.51297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.47497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-05-06T00:45:55.513017Z","caller":"traceutil/trace.go:171","msg":"trace[1766351709] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:1347; }","duration":"110.575992ms","start":"2026-05-06T00:45:55.402431Z","end":"2026-05-06T00:45:55.513007Z","steps":["trace[1766351709] 'agreement among raft nodes before linearized reading' (duration: 110.477971ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:56.080205Z","caller":"traceutil/trace.go:171","msg":"trace[1413218854] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"180.744732ms","start":"2026-05-06T00:45:55.899441Z","end":"2026-05-06T00:45:56.080186Z","steps":["trace[1413218854] 'read index received' (duration: 178.718723ms)","trace[1413218854] 'applied index is now lower than readState.Index' (duration: 2.025359ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:56.080259Z","caller":"traceutil/trace.go:171","msg":"trace[1624744128] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"195.798147ms","start":"2026-05-06T00:45:55.884427Z","end":"2026-05-06T00:45:56.080225Z","steps":["trace[1624744128] 'process raft request' (duration: 193.720687ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:56.080282Z","caller":"traceutil/trace.go:171","msg":"trace[1364181767] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"183.312228ms","start":"2026-05-06T00:45:55.896942Z","end":"2026-05-06T00:45:56.080255Z","steps":["trace[1364181767] 'process raft request' (duration: 183.210287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.080344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.887734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"warn","ts":"2026-05-06T00:45:56.080435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.582864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-05-06T00:45:56.080462Z","caller":"traceutil/trace.go:171","msg":"trace[1856980611] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1350; }","duration":"167.635454ms","start":"2026-05-06T00:45:55.912818Z","end":"2026-05-06T00:45:56.080454Z","steps":["trace[1856980611] 'agreement among raft nodes before linearized reading' (duration: 167.556233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.080484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.097039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:45:56.080507Z","caller":"traceutil/trace.go:171","msg":"trace[822566812] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1350; }","duration":"142.144239ms","start":"2026-05-06T00:45:55.938355Z","end":"2026-05-06T00:45:56.080499Z","steps":["trace[822566812] 'agreement among raft nodes before linearized reading' (duration: 142.081629ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:56.080471Z","caller":"traceutil/trace.go:171","msg":"trace[1172912794] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1350; }","duration":"181.036767ms","start":"2026-05-06T00:45:55.899407Z","end":"2026-05-06T00:45:56.080443Z","steps":["trace[1172912794] 'agreement among raft nodes before linearized reading' (duration: 180.891185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.080417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.777827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:56.080685Z","caller":"traceutil/trace.go:171","msg":"trace[601220209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"136.057732ms","start":"2026-05-06T00:45:55.944616Z","end":"2026-05-06T00:45:56.080674Z","steps":["trace[601220209] 'agreement among raft nodes before linearized reading' (duration: 135.779488ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:09.060319Z","caller":"traceutil/trace.go:171","msg":"trace[1723052131] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1450; }","duration":"144.070996ms","start":"2026-05-06T00:46:08.916234Z","end":"2026-05-06T00:46:09.060305Z","steps":["trace[1723052131] 'read index received' (duration: 143.961014ms)","trace[1723052131] 'applied index is now lower than readState.Index' (duration: 109.302µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:46:09.060393Z","caller":"traceutil/trace.go:171","msg":"trace[537769256] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"146.456678ms","start":"2026-05-06T00:46:08.913897Z","end":"2026-05-06T00:46:09.060354Z","steps":["trace[537769256] 'process raft request' (duration: 146.309286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:09.060481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.729649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-05-06T00:46:09.060509Z","caller":"traceutil/trace.go:171","msg":"trace[1906342629] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1391; }","duration":"128.780079ms","start":"2026-05-06T00:46:08.931721Z","end":"2026-05-06T00:46:09.060501Z","steps":["trace[1906342629] 'agreement among raft nodes before linearized reading' (duration: 128.676087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:09.060498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.222609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:46:09.060556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.388163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-node-lease/\" range_end:\"/registry/daemonsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:46:09.060602Z","caller":"traceutil/trace.go:171","msg":"trace[1674648342] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1391; }","duration":"144.35533ms","start":"2026-05-06T00:46:08.91622Z","end":"2026-05-06T00:46:09.060576Z","steps":["trace[1674648342] 'agreement among raft nodes before linearized reading' (duration: 144.147406ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:09.060632Z","caller":"traceutil/trace.go:171","msg":"trace[485609229] range","detail":"{range_begin:/registry/daemonsets/kube-node-lease/; range_end:/registry/daemonsets/kube-node-lease0; response_count:0; response_revision:1391; }","duration":"105.498105ms","start":"2026-05-06T00:46:08.955118Z","end":"2026-05-06T00:46:09.060616Z","steps":["trace[485609229] 'agreement among raft nodes before linearized reading' (duration: 105.387503ms)"],"step_count":1}