{"level":"warn","ts":"2026-07-07T19:56:05.87645Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T19:56:05.876585Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=controller=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70:2380","--name=controller","--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-07-07T19:56:05.876673Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T19:56:05.876687Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-07-07T19:56:05.876714Z","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-07-07T19:56:05.877462Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-07-07T19:56:05.877579Z","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":"controller","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller=https://199.204.45.70: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-07-07T19:56:05.882927Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.057123ms"} {"level":"info","ts":"2026-07-07T19:56:05.901218Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-07-07T19:56:05.90139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T19:56:05.901442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-07-07T19:56:05.901515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T19:56:05.901546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-07-07T19:56:05.901627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-07-07T19:56:05.910214Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T19:56:05.91224Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T19:56:05.91366Z","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-07-07T19:56:05.916679Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T19:56:05.916902Z","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-07-07T19:56:05.917174Z","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-07-07T19:56:05.91706Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T19:56:05.917202Z","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-07-07T19:56:05.919987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-07-07T19:56:05.921889Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-07-07T19:56:05.924865Z","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-07-07T19:56:05.925061Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-07T19:56:05.925089Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-07T19:56:05.927736Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T19:56:05.927787Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T19:56:06.403061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T19:56:06.403123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T19:56:06.403149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-07-07T19:56:06.40317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-07-07T19:56:06.403201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-07T19:56:06.403219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-07-07T19:56:06.40326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-07T19:56:06.404408Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T19:56:06.405315Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:controller ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T19:56:06.405384Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T19:56:06.405435Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T19:56:06.405697Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T19:56:06.405849Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T19:56:06.407266Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T19:56:06.407378Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T19:56:06.40741Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T19:56:06.407524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-07-07T19:56:06.410751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T19:56:29.559717Z","caller":"traceutil/trace.go:171","msg":"trace[688493315] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"130.92584ms","start":"2026-07-07T19:56:29.428771Z","end":"2026-07-07T19:56:29.559697Z","steps":["trace[688493315] 'process raft request' (duration: 107.837107ms)","trace[688493315] 'compare' (duration: 22.948126ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:56:40.199183Z","caller":"traceutil/trace.go:171","msg":"trace[1118586305] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"208.405207ms","start":"2026-07-07T19:56:39.990748Z","end":"2026-07-07T19:56:40.199153Z","steps":["trace[1118586305] 'read index received' (duration: 208.24363ms)","trace[1118586305] 'applied index is now lower than readState.Index' (duration: 160.647µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:56:40.199802Z","caller":"traceutil/trace.go:171","msg":"trace[1743300258] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"222.752622ms","start":"2026-07-07T19:56:39.977019Z","end":"2026-07-07T19:56:40.199772Z","steps":["trace[1743300258] 'process raft request' (duration: 221.943968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:40.200138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.020553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-07T19:56:40.200305Z","caller":"traceutil/trace.go:171","msg":"trace[1032477720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"209.602027ms","start":"2026-07-07T19:56:39.990687Z","end":"2026-07-07T19:56:40.200289Z","steps":["trace[1032477720] 'agreement among raft nodes before linearized reading' (duration: 209.020313ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:56:49.40742Z","caller":"traceutil/trace.go:171","msg":"trace[1495295643] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"170.735057ms","start":"2026-07-07T19:56:49.236634Z","end":"2026-07-07T19:56:49.407369Z","steps":["trace[1495295643] 'process raft request' (duration: 170.189265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:56.785087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568239766082168,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T19:56:56.932129Z","caller":"traceutil/trace.go:171","msg":"trace[402036538] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"647.864969ms","start":"2026-07-07T19:56:56.284239Z","end":"2026-07-07T19:56:56.932104Z","steps":["trace[402036538] 'read index received' (duration: 646.769798ms)","trace[402036538] 'applied index is now lower than readState.Index' (duration: 1.093661ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:56:56.932264Z","caller":"traceutil/trace.go:171","msg":"trace[476551800] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"789.58332ms","start":"2026-07-07T19:56:56.142673Z","end":"2026-07-07T19:56:56.932256Z","steps":["trace[476551800] 'process raft request' (duration: 788.257932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:56.932735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.164409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-07T19:56:56.932833Z","caller":"traceutil/trace.go:171","msg":"trace[585454111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"648.379757ms","start":"2026-07-07T19:56:56.28443Z","end":"2026-07-07T19:56:56.93281Z","steps":["trace[585454111] 'agreement among raft nodes before linearized reading' (duration: 648.011564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:56.932849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.339507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-07T19:56:56.932983Z","caller":"traceutil/trace.go:171","msg":"trace[1978963502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"637.508833ms","start":"2026-07-07T19:56:56.295439Z","end":"2026-07-07T19:56:56.932948Z","steps":["trace[1978963502] 'agreement among raft nodes before linearized reading' (duration: 637.075567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:56.93302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.433279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T19:56:56.933135Z","caller":"traceutil/trace.go:171","msg":"trace[439452808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"648.592215ms","start":"2026-07-07T19:56:56.284513Z","end":"2026-07-07T19:56:56.933105Z","steps":["trace[439452808] 'agreement among raft nodes before linearized reading' (duration: 648.117177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:56.932873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:56.284365Z","time spent":"648.501111ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T19:56:56.933189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:56.284474Z","time spent":"648.702628ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-07T19:56:56.933032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:56.295425Z","time spent":"637.598127ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T19:56:56.933838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.59024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-07T19:56:56.933965Z","caller":"traceutil/trace.go:171","msg":"trace[700874918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"649.766917ms","start":"2026-07-07T19:56:56.284183Z","end":"2026-07-07T19:56:56.933949Z","steps":["trace[700874918] 'agreement among raft nodes before linearized reading' (duration: 649.444545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:56.934046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:56.284152Z","time spent":"649.882092ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T19:56:56.934393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:56.142628Z","time spent":"789.658263ms","remote":"127.0.0.1:47954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T19:56:57.403014Z","caller":"traceutil/trace.go:171","msg":"trace[347430175] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"460.831034ms","start":"2026-07-07T19:56:56.942161Z","end":"2026-07-07T19:56:57.402992Z","steps":["trace[347430175] 'process raft request' (duration: 460.770312ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:56:57.403059Z","caller":"traceutil/trace.go:171","msg":"trace[1938034347] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"460.9966ms","start":"2026-07-07T19:56:56.942016Z","end":"2026-07-07T19:56:57.403012Z","steps":["trace[1938034347] 'process raft request' (duration: 460.797983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:57.403141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:56.942122Z","time spent":"460.952028ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T19:56:57.403316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:56.941991Z","time spent":"461.182126ms","remote":"127.0.0.1:48016","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-07-07T19:56:57.403419Z","caller":"traceutil/trace.go:171","msg":"trace[551586554] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"461.112794ms","start":"2026-07-07T19:56:56.942298Z","end":"2026-07-07T19:56:57.403411Z","steps":["trace[551586554] 'process raft request' (duration: 460.663358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:57.40347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:56.942263Z","time spent":"461.183896ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T19:56:59.142982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.475988ms","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-07-07T19:56:59.143149Z","caller":"traceutil/trace.go:171","msg":"trace[842168651] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:623; }","duration":"864.653814ms","start":"2026-07-07T19:56:58.278465Z","end":"2026-07-07T19:56:59.143119Z","steps":["trace[842168651] 'range keys from in-memory index tree' (duration: 864.453967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:59.143272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.514685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:56:59.143374Z","caller":"traceutil/trace.go:171","msg":"trace[1067092914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"790.67174ms","start":"2026-07-07T19:56:58.352677Z","end":"2026-07-07T19:56:59.143348Z","steps":["trace[1067092914] 'range keys from in-memory index tree' (duration: 790.412811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:56:59.143417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:58.352646Z","time spent":"790.762793ms","remote":"127.0.0.1:47768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T19:56:59.143604Z","caller":"traceutil/trace.go:171","msg":"trace[1576548] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"188.741049ms","start":"2026-07-07T19:56:58.954839Z","end":"2026-07-07T19:56:59.14358Z","steps":["trace[1576548] 'read index received' (duration: 153.4177ms)","trace[1576548] 'applied index is now lower than readState.Index' (duration: 35.321689ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T19:56:59.143877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.015379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-07T19:56:59.143941Z","caller":"traceutil/trace.go:171","msg":"trace[1835759931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"189.105182ms","start":"2026-07-07T19:56:58.954819Z","end":"2026-07-07T19:56:59.143924Z","steps":["trace[1835759931] 'agreement among raft nodes before linearized reading' (duration: 188.945366ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:56:59.144133Z","caller":"traceutil/trace.go:171","msg":"trace[459073258] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"717.935831ms","start":"2026-07-07T19:56:58.426156Z","end":"2026-07-07T19:56:59.144092Z","steps":["trace[459073258] 'process raft request' (duration: 682.221448ms)","trace[459073258] 'compare' (duration: 34.368746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T19:56:59.144347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:56:58.426123Z","time spent":"718.102926ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":675,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T19:56:59.262122Z","caller":"traceutil/trace.go:171","msg":"trace[1888133159] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"113.519523ms","start":"2026-07-07T19:56:59.148568Z","end":"2026-07-07T19:56:59.262088Z","steps":["trace[1888133159] 'process raft request' (duration: 103.203451ms)","trace[1888133159] 'compare' (duration: 10.119935ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:01.375613Z","caller":"traceutil/trace.go:171","msg":"trace[1033531441] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"108.205159ms","start":"2026-07-07T19:57:01.267376Z","end":"2026-07-07T19:57:01.375581Z","steps":["trace[1033531441] 'read index received' (duration: 107.688582ms)","trace[1033531441] 'applied index is now lower than readState.Index' (duration: 514.867µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:01.375705Z","caller":"traceutil/trace.go:171","msg":"trace[1373997470] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"209.273752ms","start":"2026-07-07T19:57:01.166394Z","end":"2026-07-07T19:57:01.375667Z","steps":["trace[1373997470] 'process raft request' (duration: 208.874238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:01.375891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.491598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-07T19:57:01.375941Z","caller":"traceutil/trace.go:171","msg":"trace[636535286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"108.614313ms","start":"2026-07-07T19:57:01.267315Z","end":"2026-07-07T19:57:01.375929Z","steps":["trace[636535286] 'agreement among raft nodes before linearized reading' (duration: 108.386005ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:01.53819Z","caller":"traceutil/trace.go:171","msg":"trace[778005380] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"120.236512ms","start":"2026-07-07T19:57:01.417921Z","end":"2026-07-07T19:57:01.538157Z","steps":["trace[778005380] 'read index received' (duration: 61.808674ms)","trace[778005380] 'applied index is now lower than readState.Index' (duration: 58.426468ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:01.538404Z","caller":"traceutil/trace.go:171","msg":"trace[1658652511] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"158.070582ms","start":"2026-07-07T19:57:01.380215Z","end":"2026-07-07T19:57:01.538286Z","steps":["trace[1658652511] 'process raft request' (duration: 99.54355ms)","trace[1658652511] 'compare' (duration: 58.167909ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T19:57:01.538505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.536622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-07T19:57:01.538548Z","caller":"traceutil/trace.go:171","msg":"trace[976445356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"120.704678ms","start":"2026-07-07T19:57:01.417831Z","end":"2026-07-07T19:57:01.538536Z","steps":["trace[976445356] 'agreement among raft nodes before linearized reading' (duration: 120.493761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:01.538908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.119367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-07T19:57:01.539Z","caller":"traceutil/trace.go:171","msg":"trace[814694435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"113.297373ms","start":"2026-07-07T19:57:01.425684Z","end":"2026-07-07T19:57:01.538982Z","steps":["trace[814694435] 'agreement among raft nodes before linearized reading' (duration: 112.711793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:01.538802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.644921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T19:57:01.539068Z","caller":"traceutil/trace.go:171","msg":"trace[1982700796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"113.121997ms","start":"2026-07-07T19:57:01.425925Z","end":"2026-07-07T19:57:01.539047Z","steps":["trace[1982700796] 'agreement among raft nodes before linearized reading' (duration: 112.649431ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:01.737397Z","caller":"traceutil/trace.go:171","msg":"trace[262942584] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"191.917005ms","start":"2026-07-07T19:57:01.545455Z","end":"2026-07-07T19:57:01.737372Z","steps":["trace[262942584] 'process raft request' (duration: 191.868164ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:01.737547Z","caller":"traceutil/trace.go:171","msg":"trace[1029534313] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"192.780636ms","start":"2026-07-07T19:57:01.544694Z","end":"2026-07-07T19:57:01.737475Z","steps":["trace[1029534313] 'process raft request' (duration: 192.431534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:17.849345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296568239766082498,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T19:57:18.008607Z","caller":"traceutil/trace.go:171","msg":"trace[2038673228] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"660.162744ms","start":"2026-07-07T19:57:17.348422Z","end":"2026-07-07T19:57:18.008585Z","steps":["trace[2038673228] 'read index received' (duration: 659.986559ms)","trace[2038673228] 'applied index is now lower than readState.Index' (duration: 175.155µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:18.008735Z","caller":"traceutil/trace.go:171","msg":"trace[1811560450] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"735.63246ms","start":"2026-07-07T19:57:17.273056Z","end":"2026-07-07T19:57:18.008689Z","steps":["trace[1811560450] 'process raft request' (duration: 735.349942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.009639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.34851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.009691Z","caller":"traceutil/trace.go:171","msg":"trace[646989017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"661.322989ms","start":"2026-07-07T19:57:17.348354Z","end":"2026-07-07T19:57:18.009677Z","steps":["trace[646989017] 'agreement among raft nodes before linearized reading' (duration: 660.379461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.009741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.348332Z","time spent":"661.396021ms","remote":"127.0.0.1:47768","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-07-07T19:57:18.009874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.273024Z","time spent":"736.555078ms","remote":"127.0.0.1:47954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4035,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T19:57:18.011036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.443687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.011095Z","caller":"traceutil/trace.go:171","msg":"trace[173292583] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:782; }","duration":"390.498389ms","start":"2026-07-07T19:57:17.620571Z","end":"2026-07-07T19:57:18.011069Z","steps":["trace[173292583] 'agreement among raft nodes before linearized reading' (duration: 390.410406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.011128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.620529Z","time spent":"390.587901ms","remote":"127.0.0.1:54506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-07T19:57:18.010952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.146794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.011295Z","caller":"traceutil/trace.go:171","msg":"trace[1052184406] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:782; }","duration":"474.485424ms","start":"2026-07-07T19:57:17.536754Z","end":"2026-07-07T19:57:18.011239Z","steps":["trace[1052184406] 'agreement among raft nodes before linearized reading' (duration: 474.117023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.011338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.536732Z","time spent":"474.591217ms","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-07T19:57:18.011401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.432224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.011541Z","caller":"traceutil/trace.go:171","msg":"trace[1145220535] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:782; }","duration":"424.565058ms","start":"2026-07-07T19:57:17.586906Z","end":"2026-07-07T19:57:18.011471Z","steps":["trace[1145220535] 'agreement among raft nodes before linearized reading' (duration: 424.386693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.01203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.586894Z","time spent":"425.114675ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-07-07T19:57:18.01205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.822005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.012099Z","caller":"traceutil/trace.go:171","msg":"trace[511660616] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:782; }","duration":"425.916308ms","start":"2026-07-07T19:57:17.586171Z","end":"2026-07-07T19:57:18.012087Z","steps":["trace[511660616] 'agreement among raft nodes before linearized reading' (duration: 425.842876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.012138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.586142Z","time spent":"425.9782ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-07-07T19:57:18.01229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.005032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-07T19:57:18.012389Z","caller":"traceutil/trace.go:171","msg":"trace[1967011326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"254.120236ms","start":"2026-07-07T19:57:17.758223Z","end":"2026-07-07T19:57:18.012343Z","steps":["trace[1967011326] 'agreement among raft nodes before linearized reading' (duration: 253.843638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.012617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.519906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.012671Z","caller":"traceutil/trace.go:171","msg":"trace[2052860012] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:782; }","duration":"426.597568ms","start":"2026-07-07T19:57:17.586046Z","end":"2026-07-07T19:57:18.012644Z","steps":["trace[2052860012] 'agreement among raft nodes before linearized reading' (duration: 426.350931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.012701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.586021Z","time spent":"426.67116ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-07-07T19:57:18.013126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.273317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.013165Z","caller":"traceutil/trace.go:171","msg":"trace[1054658855] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:782; }","duration":"475.321508ms","start":"2026-07-07T19:57:17.537826Z","end":"2026-07-07T19:57:18.013147Z","steps":["trace[1054658855] 'agreement among raft nodes before linearized reading' (duration: 475.154064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.013141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.515742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-07T19:57:18.013195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.537816Z","time spent":"475.369979ms","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"info","ts":"2026-07-07T19:57:18.013201Z","caller":"traceutil/trace.go:171","msg":"trace[773639592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:782; }","duration":"186.635366ms","start":"2026-07-07T19:57:17.826554Z","end":"2026-07-07T19:57:18.01319Z","steps":["trace[773639592] 'agreement among raft nodes before linearized reading' (duration: 186.298596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.013429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.064634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.013524Z","caller":"traceutil/trace.go:171","msg":"trace[1151620518] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:782; }","duration":"408.126776ms","start":"2026-07-07T19:57:17.605339Z","end":"2026-07-07T19:57:18.013466Z","steps":["trace[1151620518] 'agreement among raft nodes before linearized reading' (duration: 407.794047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.013568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.605335Z","time spent":"408.220719ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-07-07T19:57:18.013633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.471602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.013684Z","caller":"traceutil/trace.go:171","msg":"trace[2064905329] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:782; }","duration":"476.556284ms","start":"2026-07-07T19:57:17.537104Z","end":"2026-07-07T19:57:18.01366Z","steps":["trace[2064905329] 'agreement among raft nodes before linearized reading' (duration: 476.362669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.013729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.537073Z","time spent":"476.646017ms","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-07-07T19:57:18.014119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.706542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T19:57:18.014175Z","caller":"traceutil/trace.go:171","msg":"trace[1226116410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:782; }","duration":"192.811125ms","start":"2026-07-07T19:57:17.821353Z","end":"2026-07-07T19:57:18.014165Z","steps":["trace[1226116410] 'agreement among raft nodes before linearized reading' (duration: 192.450974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.01515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.699623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.015219Z","caller":"traceutil/trace.go:171","msg":"trace[1169492701] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:782; }","duration":"393.784285ms","start":"2026-07-07T19:57:17.621405Z","end":"2026-07-07T19:57:18.015189Z","steps":["trace[1169492701] 'agreement among raft nodes before linearized reading' (duration: 393.660921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.015263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.621384Z","time spent":"393.865087ms","remote":"127.0.0.1:54506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-07-07T19:57:18.015547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.957209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.015645Z","caller":"traceutil/trace.go:171","msg":"trace[808912830] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:782; }","duration":"395.029061ms","start":"2026-07-07T19:57:17.620551Z","end":"2026-07-07T19:57:18.01558Z","steps":["trace[808912830] 'agreement among raft nodes before linearized reading' (duration: 394.948329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.015685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.620534Z","time spent":"395.135384ms","remote":"127.0.0.1:54506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-07T19:57:18.015798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.855176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.015833Z","caller":"traceutil/trace.go:171","msg":"trace[1185085993] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:782; }","duration":"411.01437ms","start":"2026-07-07T19:57:17.604808Z","end":"2026-07-07T19:57:18.015823Z","steps":["trace[1185085993] 'agreement among raft nodes before linearized reading' (duration: 410.942528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.015868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.604786Z","time spent":"411.067112ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-07T19:57:18.016052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.558715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-07T19:57:18.01614Z","caller":"traceutil/trace.go:171","msg":"trace[2114365441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:782; }","duration":"241.684289ms","start":"2026-07-07T19:57:17.774424Z","end":"2026-07-07T19:57:18.016108Z","steps":["trace[2114365441] 'agreement among raft nodes before linearized reading' (duration: 240.930388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.016243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.338649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:18.016316Z","caller":"traceutil/trace.go:171","msg":"trace[1923280030] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:782; }","duration":"411.483344ms","start":"2026-07-07T19:57:17.604808Z","end":"2026-07-07T19:57:18.016291Z","steps":["trace[1923280030] 'agreement among raft nodes before linearized reading' (duration: 411.37095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:18.016709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T19:57:17.60479Z","time spent":"411.906306ms","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-07-07T19:57:23.619019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.766101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T19:57:23.619121Z","caller":"traceutil/trace.go:171","msg":"trace[667849050] linearizableReadLoop","detail":"{readStateIndex:876; appliedIndex:875; }","duration":"272.138999ms","start":"2026-07-07T19:57:23.346967Z","end":"2026-07-07T19:57:23.619106Z","steps":["trace[667849050] 'read index received' (duration: 126.667528ms)","trace[667849050] 'applied index is now lower than readState.Index' (duration: 145.470051ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T19:57:23.619175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.2053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T19:57:23.619195Z","caller":"traceutil/trace.go:171","msg":"trace[1001438435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"272.250822ms","start":"2026-07-07T19:57:23.346937Z","end":"2026-07-07T19:57:23.619188Z","steps":["trace[1001438435] 'agreement among raft nodes before linearized reading' (duration: 272.207381ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:23.619155Z","caller":"traceutil/trace.go:171","msg":"trace[1848429332] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"276.923422ms","start":"2026-07-07T19:57:23.342193Z","end":"2026-07-07T19:57:23.619117Z","steps":["trace[1848429332] 'process raft request' (duration: 131.500073ms)","trace[1848429332] 'compare' (duration: 144.273397ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:23.858242Z","caller":"traceutil/trace.go:171","msg":"trace[641612800] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:876; }","duration":"189.212694ms","start":"2026-07-07T19:57:23.668998Z","end":"2026-07-07T19:57:23.858211Z","steps":["trace[641612800] 'read index received' (duration: 9.121493ms)","trace[641612800] 'applied index is now lower than readState.Index' (duration: 180.089791ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:23.858346Z","caller":"traceutil/trace.go:171","msg":"trace[1622163119] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"231.686205ms","start":"2026-07-07T19:57:23.626597Z","end":"2026-07-07T19:57:23.858283Z","steps":["trace[1622163119] 'process raft request' (duration: 230.012399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:23.858535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.451898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T19:57:23.858584Z","caller":"traceutil/trace.go:171","msg":"trace[411169283] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:851; }","duration":"119.632223ms","start":"2026-07-07T19:57:23.738937Z","end":"2026-07-07T19:57:23.858569Z","steps":["trace[411169283] 'agreement among raft nodes before linearized reading' (duration: 119.462229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:23.858556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.455062ms","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-07-07T19:57:23.858678Z","caller":"traceutil/trace.go:171","msg":"trace[1890697794] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:851; }","duration":"189.681548ms","start":"2026-07-07T19:57:23.668967Z","end":"2026-07-07T19:57:23.858648Z","steps":["trace[1890697794] 'agreement among raft nodes before linearized reading' (duration: 189.38722ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:36.350469Z","caller":"traceutil/trace.go:171","msg":"trace[227794380] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:980; }","duration":"111.191652ms","start":"2026-07-07T19:57:36.239248Z","end":"2026-07-07T19:57:36.35044Z","steps":["trace[227794380] 'read index received' (duration: 80.413407ms)","trace[227794380] 'applied index is now lower than readState.Index' (duration: 30.776845ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:36.350844Z","caller":"traceutil/trace.go:171","msg":"trace[3356744] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"122.121763ms","start":"2026-07-07T19:57:36.228687Z","end":"2026-07-07T19:57:36.350808Z","steps":["trace[3356744] 'process raft request' (duration: 96.508827ms)","trace[3356744] 'compare' (duration: 25.044362ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:36.350935Z","caller":"traceutil/trace.go:171","msg":"trace[324410244] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"121.803075ms","start":"2026-07-07T19:57:36.229082Z","end":"2026-07-07T19:57:36.350885Z","steps":["trace[324410244] 'process raft request' (duration: 121.302762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:36.350911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.598081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-07T19:57:36.351188Z","caller":"traceutil/trace.go:171","msg":"trace[149332946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"111.96377ms","start":"2026-07-07T19:57:36.239209Z","end":"2026-07-07T19:57:36.351172Z","steps":["trace[149332946] 'agreement among raft nodes before linearized reading' (duration: 111.467857ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:42.358843Z","caller":"traceutil/trace.go:171","msg":"trace[857407477] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"168.088177ms","start":"2026-07-07T19:57:42.190692Z","end":"2026-07-07T19:57:42.35878Z","steps":["trace[857407477] 'read index received' (duration: 167.894442ms)","trace[857407477] 'applied index is now lower than readState.Index' (duration: 193.015µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:42.358847Z","caller":"traceutil/trace.go:171","msg":"trace[2121496318] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"170.400752ms","start":"2026-07-07T19:57:42.188425Z","end":"2026-07-07T19:57:42.358826Z","steps":["trace[2121496318] 'process raft request' (duration: 170.214648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:42.358998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.283351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-07-07T19:57:42.359033Z","caller":"traceutil/trace.go:171","msg":"trace[599640863] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:970; }","duration":"168.350152ms","start":"2026-07-07T19:57:42.190672Z","end":"2026-07-07T19:57:42.359022Z","steps":["trace[599640863] 'agreement among raft nodes before linearized reading' (duration: 168.271471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:42.643312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.809272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T19:57:42.643414Z","caller":"traceutil/trace.go:171","msg":"trace[1934011526] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"196.55657ms","start":"2026-07-07T19:57:42.446843Z","end":"2026-07-07T19:57:42.643399Z","steps":["trace[1934011526] 'read index received' (duration: 30.524242ms)","trace[1934011526] 'applied index is now lower than readState.Index' (duration: 166.031418ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T19:57:42.643561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.712453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-07-07T19:57:42.643585Z","caller":"traceutil/trace.go:171","msg":"trace[1459467761] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:971; }","duration":"196.789295ms","start":"2026-07-07T19:57:42.446789Z","end":"2026-07-07T19:57:42.643578Z","steps":["trace[1459467761] 'agreement among raft nodes before linearized reading' (duration: 196.653242ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:42.643641Z","caller":"traceutil/trace.go:171","msg":"trace[1932640620] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"243.777447ms","start":"2026-07-07T19:57:42.39984Z","end":"2026-07-07T19:57:42.643617Z","steps":["trace[1932640620] 'process raft request' (duration: 77.586026ms)","trace[1932640620] 'compare' (duration: 165.7113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T19:57:42.643939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.831213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-07-07T19:57:42.644058Z","caller":"traceutil/trace.go:171","msg":"trace[1611778242] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:972; }","duration":"195.963467ms","start":"2026-07-07T19:57:42.448061Z","end":"2026-07-07T19:57:42.644025Z","steps":["trace[1611778242] 'agreement among raft nodes before linearized reading' (duration: 195.760352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:42.644106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.04236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-07T19:57:42.644149Z","caller":"traceutil/trace.go:171","msg":"trace[2039304715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"164.098261ms","start":"2026-07-07T19:57:42.480038Z","end":"2026-07-07T19:57:42.644136Z","steps":["trace[2039304715] 'agreement among raft nodes before linearized reading' (duration: 163.960088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:42.644191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.406539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-07T19:57:42.644238Z","caller":"traceutil/trace.go:171","msg":"trace[1636822735] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"156.649015ms","start":"2026-07-07T19:57:42.487571Z","end":"2026-07-07T19:57:42.64422Z","steps":["trace[1636822735] 'process raft request' (duration: 156.051801ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:42.644259Z","caller":"traceutil/trace.go:171","msg":"trace[1402224514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"164.489431ms","start":"2026-07-07T19:57:42.479756Z","end":"2026-07-07T19:57:42.644245Z","steps":["trace[1402224514] 'agreement among raft nodes before linearized reading' (duration: 164.279426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:42.644276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.796583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-07T19:57:42.644374Z","caller":"traceutil/trace.go:171","msg":"trace[918674602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"168.945637ms","start":"2026-07-07T19:57:42.475403Z","end":"2026-07-07T19:57:42.644349Z","steps":["trace[918674602] 'agreement among raft nodes before linearized reading' (duration: 168.650049ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:44.809369Z","caller":"traceutil/trace.go:171","msg":"trace[700623666] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"152.767938ms","start":"2026-07-07T19:57:44.656572Z","end":"2026-07-07T19:57:44.80934Z","steps":["trace[700623666] 'read index received' (duration: 152.521032ms)","trace[700623666] 'applied index is now lower than readState.Index' (duration: 245.626µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:44.809456Z","caller":"traceutil/trace.go:171","msg":"trace[1509315780] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"154.320594ms","start":"2026-07-07T19:57:44.655108Z","end":"2026-07-07T19:57:44.809429Z","steps":["trace[1509315780] 'process raft request' (duration: 154.033327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:44.80968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.060844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-07-07T19:57:44.809761Z","caller":"traceutil/trace.go:171","msg":"trace[401234971] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:979; }","duration":"153.184917ms","start":"2026-07-07T19:57:44.656556Z","end":"2026-07-07T19:57:44.809741Z","steps":["trace[401234971] 'agreement among raft nodes before linearized reading' (duration: 152.987852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:44.809764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.779261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-07T19:57:44.809842Z","caller":"traceutil/trace.go:171","msg":"trace[1594258318] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:979; }","duration":"147.885285ms","start":"2026-07-07T19:57:44.661939Z","end":"2026-07-07T19:57:44.809824Z","steps":["trace[1594258318] 'agreement among raft nodes before linearized reading' (duration: 147.619288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:44.810003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.266844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-07T19:57:44.810032Z","caller":"traceutil/trace.go:171","msg":"trace[693118219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"142.311964ms","start":"2026-07-07T19:57:44.667712Z","end":"2026-07-07T19:57:44.810024Z","steps":["trace[693118219] 'agreement among raft nodes before linearized reading' (duration: 142.212882ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:53.022006Z","caller":"traceutil/trace.go:171","msg":"trace[1802825520] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1043; }","duration":"143.76857ms","start":"2026-07-07T19:57:52.878212Z","end":"2026-07-07T19:57:53.021981Z","steps":["trace[1802825520] 'read index received' (duration: 113.428408ms)","trace[1802825520] 'applied index is now lower than readState.Index' (duration: 30.339152ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T19:57:53.022345Z","caller":"traceutil/trace.go:171","msg":"trace[1656066193] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"156.700445ms","start":"2026-07-07T19:57:52.865629Z","end":"2026-07-07T19:57:53.022329Z","steps":["trace[1656066193] 'process raft request' (duration: 156.307756ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:53.022356Z","caller":"traceutil/trace.go:171","msg":"trace[921114138] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"157.44497ms","start":"2026-07-07T19:57:52.864875Z","end":"2026-07-07T19:57:53.02232Z","steps":["trace[921114138] 'process raft request' (duration: 157.009651ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:53.022413Z","caller":"traceutil/trace.go:171","msg":"trace[1185878429] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"158.015292ms","start":"2026-07-07T19:57:52.864342Z","end":"2026-07-07T19:57:53.022357Z","steps":["trace[1185878429] 'process raft request' (duration: 127.269001ms)","trace[1185878429] 'compare' (duration: 29.969675ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T19:57:53.022527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.305292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-07T19:57:53.022598Z","caller":"traceutil/trace.go:171","msg":"trace[665806851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1012; }","duration":"144.407604ms","start":"2026-07-07T19:57:52.878167Z","end":"2026-07-07T19:57:53.022575Z","steps":["trace[665806851] 'agreement among raft nodes before linearized reading' (duration: 144.22334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T19:57:53.022636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.206132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" ","response":"range_response_count:1 size:715"} {"level":"warn","ts":"2026-07-07T19:57:53.022464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.358798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-07-07T19:57:53.022754Z","caller":"traceutil/trace.go:171","msg":"trace[92506901] range","detail":"{range_begin:/registry/clusterrolebindings/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1012; }","duration":"136.713005ms","start":"2026-07-07T19:57:52.886028Z","end":"2026-07-07T19:57:53.022741Z","steps":["trace[92506901] 'agreement among raft nodes before linearized reading' (duration: 136.331967ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:57:53.022677Z","caller":"traceutil/trace.go:171","msg":"trace[1043806744] range","detail":"{range_begin:/registry/clusterrolebindings/system:volume-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"130.272384ms","start":"2026-07-07T19:57:52.892392Z","end":"2026-07-07T19:57:53.022665Z","steps":["trace[1043806744] 'agreement among raft nodes before linearized reading' (duration: 130.176282ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:58:09.248388Z","caller":"traceutil/trace.go:171","msg":"trace[1541093212] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"112.966791ms","start":"2026-07-07T19:58:09.135397Z","end":"2026-07-07T19:58:09.248364Z","steps":["trace[1541093212] 'read index received' (duration: 112.761187ms)","trace[1541093212] 'applied index is now lower than readState.Index' (duration: 204.584µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T19:58:09.249015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.593734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-07T19:58:09.249088Z","caller":"traceutil/trace.go:171","msg":"trace[192784124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1062; }","duration":"113.716306ms","start":"2026-07-07T19:58:09.135356Z","end":"2026-07-07T19:58:09.249072Z","steps":["trace[192784124] 'agreement among raft nodes before linearized reading' (duration: 113.507851ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:58:09.249076Z","caller":"traceutil/trace.go:171","msg":"trace[1441790807] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"151.092657ms","start":"2026-07-07T19:58:09.097952Z","end":"2026-07-07T19:58:09.249045Z","steps":["trace[1441790807] 'process raft request' (duration: 150.2755ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:58:09.417962Z","caller":"traceutil/trace.go:171","msg":"trace[1147953755] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"163.174323ms","start":"2026-07-07T19:58:09.25475Z","end":"2026-07-07T19:58:09.417924Z","steps":["trace[1147953755] 'process raft request' (duration: 162.858187ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:58:09.425387Z","caller":"traceutil/trace.go:171","msg":"trace[2049674486] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"170.118659ms","start":"2026-07-07T19:58:09.255244Z","end":"2026-07-07T19:58:09.425362Z","steps":["trace[2049674486] 'process raft request' (duration: 169.933855ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T19:58:09.425788Z","caller":"traceutil/trace.go:171","msg":"trace[1919845631] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"169.732132ms","start":"2026-07-07T19:58:09.256024Z","end":"2026-07-07T19:58:09.425756Z","steps":["trace[1919845631] 'process raft request' (duration: 169.288373ms)"],"step_count":1}