{"level":"warn","ts":"2026-01-19T16:11:20.78008Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-19T16:11:20.780187Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.124: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.124:2380","--initial-cluster=instance=https://199.19.213.124:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.124:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.124: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-01-19T16:11:20.780281Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-19T16:11:20.780318Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-01-19T16:11:20.780338Z","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-01-19T16:11:20.781107Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"]} {"level":"info","ts":"2026-01-19T16:11:20.781208Z","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":2,"max-cpu-available":2,"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.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.124: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-01-19T16:11:20.793977Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.498373ms"} {"level":"info","ts":"2026-01-19T16:11:20.812988Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6894f7e0c0041bce","cluster-id":"db11c089a44de10a"} {"level":"info","ts":"2026-01-19T16:11:20.814003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=()"} {"level":"info","ts":"2026-01-19T16:11:20.81408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 0"} {"level":"info","ts":"2026-01-19T16:11:20.814163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6894f7e0c0041bce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-19T16:11:20.8142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 1"} {"level":"info","ts":"2026-01-19T16:11:20.814486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"warn","ts":"2026-01-19T16:11:20.827119Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-19T16:11:20.829866Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-19T16:11:20.83166Z","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-01-19T16:11:20.834367Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6894f7e0c0041bce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-19T16:11:20.83534Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6894f7e0c0041bce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-19T16:11:20.835772Z","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-01-19T16:11:20.83582Z","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-01-19T16:11:20.836404Z","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-01-19T16:11:20.83674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"info","ts":"2026-01-19T16:11:20.83696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","added-peer-id":"6894f7e0c0041bce","added-peer-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-01-19T16:11:20.846591Z","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-01-19T16:11:20.846883Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6894f7e0c0041bce","initial-advertise-peer-urls":["https://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-19T16:11:20.846915Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-19T16:11:20.847348Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-01-19T16:11:20.847378Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-01-19T16:11:21.314798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce is starting a new election at term 1"} {"level":"info","ts":"2026-01-19T16:11:21.314924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became pre-candidate at term 1"} {"level":"info","ts":"2026-01-19T16:11:21.317418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgPreVoteResp from 6894f7e0c0041bce at term 1"} {"level":"info","ts":"2026-01-19T16:11:21.322718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became candidate at term 2"} {"level":"info","ts":"2026-01-19T16:11:21.322757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgVoteResp from 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-01-19T16:11:21.322801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became leader at term 2"} {"level":"info","ts":"2026-01-19T16:11:21.322822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6894f7e0c0041bce elected leader 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-01-19T16:11:21.369602Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:11:21.374276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T16:11:21.386961Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.124:2379"} {"level":"info","ts":"2026-01-19T16:11:21.387086Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:11:21.387156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:11:21.387181Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:11:21.402344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T16:11:21.404287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-19T16:11:21.374244Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6894f7e0c0041bce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.124:2379]}","request-path":"/0/members/6894f7e0c0041bce/attributes","cluster-id":"db11c089a44de10a","publish-timeout":"7s"} {"level":"info","ts":"2026-01-19T16:11:21.426462Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-19T16:11:21.426555Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-19T16:11:33.222779Z","caller":"traceutil/trace.go:171","msg":"trace[285136600] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:369; }","duration":"151.82424ms","start":"2026-01-19T16:11:33.070936Z","end":"2026-01-19T16:11:33.22276Z","steps":["trace[285136600] 'read index received' (duration: 151.273173ms)","trace[285136600] 'applied index is now lower than readState.Index' (duration: 549.997µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:11:33.2233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.342037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-01-19T16:11:33.223493Z","caller":"traceutil/trace.go:171","msg":"trace[86330409] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:363; }","duration":"152.591835ms","start":"2026-01-19T16:11:33.070866Z","end":"2026-01-19T16:11:33.223458Z","steps":["trace[86330409] 'agreement among raft nodes before linearized reading' (duration: 152.281675ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:11:33.223898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.972389ms","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-01-19T16:11:33.223995Z","caller":"traceutil/trace.go:171","msg":"trace[195064209] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:363; }","duration":"104.116244ms","start":"2026-01-19T16:11:33.119867Z","end":"2026-01-19T16:11:33.223983Z","steps":["trace[195064209] 'agreement among raft nodes before linearized reading' (duration: 103.99327ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:11:33.223061Z","caller":"traceutil/trace.go:171","msg":"trace[869952165] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"192.132429ms","start":"2026-01-19T16:11:33.030886Z","end":"2026-01-19T16:11:33.223018Z","steps":["trace[869952165] 'process raft request' (duration: 191.420206ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:11:39.113897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054428054,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T16:11:39.275887Z","caller":"traceutil/trace.go:171","msg":"trace[1532532884] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"711.145507ms","start":"2026-01-19T16:11:38.564714Z","end":"2026-01-19T16:11:39.275859Z","steps":["trace[1532532884] 'process raft request' (duration: 711.013152ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:11:39.277183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:11:38.564702Z","time spent":"711.256741ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:11:39.823797Z","caller":"traceutil/trace.go:171","msg":"trace[759797220] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:425; }","duration":"1.210547253s","start":"2026-01-19T16:11:38.613212Z","end":"2026-01-19T16:11:39.823759Z","steps":["trace[759797220] 'read index received' (duration: 663.563474ms)","trace[759797220] 'applied index is now lower than readState.Index' (duration: 546.982549ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:11:39.824083Z","caller":"traceutil/trace.go:171","msg":"trace[1558733252] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.039859085s","start":"2026-01-19T16:11:38.784182Z","end":"2026-01-19T16:11:39.824041Z","steps":["trace[1558733252] 'process raft request' (duration: 1.039524864s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:11:39.824225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210994847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-19T16:11:39.82425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:11:38.784155Z","time spent":"1.04000885s","remote":"127.0.0.1:37642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4042,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:11:39.826914Z","caller":"traceutil/trace.go:171","msg":"trace[1335838193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"1.21109002s","start":"2026-01-19T16:11:38.613162Z","end":"2026-01-19T16:11:39.824252Z","steps":["trace[1335838193] 'agreement among raft nodes before linearized reading' (duration: 1.211015437s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:11:39.826971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:11:38.613139Z","time spent":"1.213811347s","remote":"127.0.0.1:37492","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-01-19T16:11:39.827117Z","caller":"traceutil/trace.go:171","msg":"trace[281079760] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"1.095983882s","start":"2026-01-19T16:11:38.728608Z","end":"2026-01-19T16:11:39.824592Z","steps":["trace[281079760] 'process raft request' (duration: 1.077232478s)","trace[281079760] 'compare' (duration: 17.67544ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:11:39.827196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:11:38.728583Z","time spent":"1.098556786s","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:11:42.467179Z","caller":"traceutil/trace.go:171","msg":"trace[2068845023] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"264.600513ms","start":"2026-01-19T16:11:42.202553Z","end":"2026-01-19T16:11:42.467153Z","steps":["trace[2068845023] 'process raft request' (duration: 187.653404ms)","trace[2068845023] 'compare' (duration: 76.635819ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:11:56.468871Z","caller":"traceutil/trace.go:171","msg":"trace[1344618662] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"167.011219ms","start":"2026-01-19T16:11:56.301837Z","end":"2026-01-19T16:11:56.468848Z","steps":["trace[1344618662] 'process raft request' (duration: 121.176713ms)","trace[1344618662] 'compare' (duration: 45.401832ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:11:56.471286Z","caller":"traceutil/trace.go:171","msg":"trace[820004525] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"169.384186ms","start":"2026-01-19T16:11:56.30188Z","end":"2026-01-19T16:11:56.471264Z","steps":["trace[820004525] 'process raft request' (duration: 166.648818ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:11:56.472978Z","caller":"traceutil/trace.go:171","msg":"trace[1892496729] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"169.538411ms","start":"2026-01-19T16:11:56.3034Z","end":"2026-01-19T16:11:56.472938Z","steps":["trace[1892496729] 'process raft request' (duration: 167.816556ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:11:56.47417Z","caller":"traceutil/trace.go:171","msg":"trace[289929430] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"159.948942ms","start":"2026-01-19T16:11:56.314195Z","end":"2026-01-19T16:11:56.474144Z","steps":["trace[289929430] 'process raft request' (duration: 157.589306ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:11:56.47442Z","caller":"traceutil/trace.go:171","msg":"trace[366884799] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"139.909227ms","start":"2026-01-19T16:11:56.334504Z","end":"2026-01-19T16:11:56.474413Z","steps":["trace[366884799] 'process raft request' (duration: 138.378557ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:11:56.474632Z","caller":"traceutil/trace.go:171","msg":"trace[1899777868] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"137.994345ms","start":"2026-01-19T16:11:56.336626Z","end":"2026-01-19T16:11:56.47462Z","steps":["trace[1899777868] 'process raft request' (duration: 136.307051ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:11:56.474936Z","caller":"traceutil/trace.go:171","msg":"trace[1429733471] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"129.13449ms","start":"2026-01-19T16:11:56.345791Z","end":"2026-01-19T16:11:56.474925Z","steps":["trace[1429733471] 'process raft request' (duration: 127.211098ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:11:56.712556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.839142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:11:56.712857Z","caller":"traceutil/trace.go:171","msg":"trace[620999176] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:533; }","duration":"180.224266ms","start":"2026-01-19T16:11:56.532614Z","end":"2026-01-19T16:11:56.712838Z","steps":["trace[620999176] 'read index received' (duration: 49.739682ms)","trace[620999176] 'applied index is now lower than readState.Index' (duration: 130.483684ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:11:56.713297Z","caller":"traceutil/trace.go:171","msg":"trace[923612848] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"182.595602ms","start":"2026-01-19T16:11:56.530687Z","end":"2026-01-19T16:11:56.713282Z","steps":["trace[923612848] 'process raft request' (duration: 182.013253ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:11:56.71345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.802474ms","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-01-19T16:11:56.713536Z","caller":"traceutil/trace.go:171","msg":"trace[1849034874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"180.936388ms","start":"2026-01-19T16:11:56.532577Z","end":"2026-01-19T16:11:56.713514Z","steps":["trace[1849034874] 'agreement among raft nodes before linearized reading' (duration: 180.707471ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:11:56.713806Z","caller":"traceutil/trace.go:171","msg":"trace[1639787449] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"188.066878ms","start":"2026-01-19T16:11:56.525723Z","end":"2026-01-19T16:11:56.71379Z","steps":["trace[1639787449] 'process raft request' (duration: 56.620204ms)","trace[1639787449] 'compare' (duration: 129.665837ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:11:56.71432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.767824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329932"} {"level":"info","ts":"2026-01-19T16:11:56.714362Z","caller":"traceutil/trace.go:171","msg":"trace[776749096] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:521; }","duration":"126.864656ms","start":"2026-01-19T16:11:56.587483Z","end":"2026-01-19T16:11:56.714348Z","steps":["trace[776749096] 'agreement among raft nodes before linearized reading' (duration: 126.36646ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:01.2094Z","caller":"traceutil/trace.go:171","msg":"trace[1058309986] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"111.966716ms","start":"2026-01-19T16:12:01.097338Z","end":"2026-01-19T16:12:01.209305Z","steps":["trace[1058309986] 'process raft request' (duration: 111.840912ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:02.116314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054428348,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:12:02.306127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.609113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:12:02.30632Z","caller":"traceutil/trace.go:171","msg":"trace[1019713261] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"812.052046ms","start":"2026-01-19T16:12:01.494253Z","end":"2026-01-19T16:12:02.306305Z","steps":["trace[1019713261] 'process raft request' (duration: 811.960413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:02.306362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:01.494221Z","time spent":"812.124098ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:12:02.306779Z","caller":"traceutil/trace.go:171","msg":"trace[1542248077] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"896.396843ms","start":"2026-01-19T16:12:01.41035Z","end":"2026-01-19T16:12:02.306747Z","steps":["trace[1542248077] 'process raft request' (duration: 123.801658ms)","trace[1542248077] 'compare' (duration: 771.470229ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:12:02.306838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:01.410338Z","time spent":"896.476545ms","remote":"127.0.0.1:37702","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-01-19T16:12:03.616246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00093783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-19T16:12:03.616364Z","caller":"traceutil/trace.go:171","msg":"trace[1263270710] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001117786s","start":"2026-01-19T16:12:01.615224Z","end":"2026-01-19T16:12:03.616342Z","steps":["trace[1263270710] 'agreement among raft nodes before linearized reading' (duration: 2.000970481s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.616428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:01.615208Z","time spent":"2.001206089s","remote":"127.0.0.1:37492","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-01-19T16:12:03.746708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.984019ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-19T16:12:03.746974Z","caller":"traceutil/trace.go:171","msg":"trace[1508769251] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.432314815s","start":"2026-01-19T16:12:02.314643Z","end":"2026-01-19T16:12:03.746958Z","steps":["trace[1508769251] 'process raft request' (duration: 1.432263014s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.747064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.314624Z","time spent":"1.432380778s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:12:03.747419Z","caller":"traceutil/trace.go:171","msg":"trace[586079591] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.646512885s","start":"2026-01-19T16:12:02.100896Z","end":"2026-01-19T16:12:03.747409Z","steps":["trace[586079591] 'process raft request' (duration: 1.645904715s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.747463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.100875Z","time spent":"1.646566567s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:12:03.749104Z","caller":"traceutil/trace.go:171","msg":"trace[2062240996] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:585; }","duration":"2.13380158s","start":"2026-01-19T16:12:01.615265Z","end":"2026-01-19T16:12:03.749067Z","steps":["trace[2062240996] 'read index received' (duration: 541.842942ms)","trace[2062240996] 'applied index is now lower than readState.Index' (duration: 1.591957388s)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:12:03.749299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.054313069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.749343Z","caller":"traceutil/trace.go:171","msg":"trace[1916665469] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:572; }","duration":"2.054396253s","start":"2026-01-19T16:12:01.694937Z","end":"2026-01-19T16:12:03.749334Z","steps":["trace[1916665469] 'agreement among raft nodes before linearized reading' (duration: 2.05432771s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.749366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:01.694921Z","time spent":"2.054439074s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:12:03.750687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.511459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.750777Z","caller":"traceutil/trace.go:171","msg":"trace[1854489283] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:572; }","duration":"784.671085ms","start":"2026-01-19T16:12:02.966087Z","end":"2026-01-19T16:12:03.750758Z","steps":["trace[1854489283] 'agreement among raft nodes before linearized reading' (duration: 784.454668ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.750843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.96608Z","time spent":"784.754977ms","remote":"127.0.0.1:44950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:12:03.751071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.048847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.751096Z","caller":"traceutil/trace.go:171","msg":"trace[931096297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"134.105819ms","start":"2026-01-19T16:12:03.616983Z","end":"2026-01-19T16:12:03.751089Z","steps":["trace[931096297] 'agreement among raft nodes before linearized reading' (duration: 134.059258ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.751299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.794916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-01-19T16:12:03.751327Z","caller":"traceutil/trace.go:171","msg":"trace[1531953378] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"258.886609ms","start":"2026-01-19T16:12:03.492432Z","end":"2026-01-19T16:12:03.751318Z","steps":["trace[1531953378] 'agreement among raft nodes before linearized reading' (duration: 258.762395ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.751556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.101185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-01-19T16:12:03.751587Z","caller":"traceutil/trace.go:171","msg":"trace[1819956197] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"458.164008ms","start":"2026-01-19T16:12:03.293415Z","end":"2026-01-19T16:12:03.751579Z","steps":["trace[1819956197] 'agreement among raft nodes before linearized reading' (duration: 458.049904ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.751608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:03.293377Z","time spent":"458.22589ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:12:03.751739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.729918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-01-19T16:12:03.751764Z","caller":"traceutil/trace.go:171","msg":"trace[1395256729] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"658.79145ms","start":"2026-01-19T16:12:03.092966Z","end":"2026-01-19T16:12:03.751758Z","steps":["trace[1395256729] 'agreement among raft nodes before linearized reading' (duration: 658.726808ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.751796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:03.092937Z","time spent":"658.840651ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:12:03.75189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.571134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.751916Z","caller":"traceutil/trace.go:171","msg":"trace[1468939576] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:572; }","duration":"731.621806ms","start":"2026-01-19T16:12:03.020289Z","end":"2026-01-19T16:12:03.75191Z","steps":["trace[1468939576] 'agreement among raft nodes before linearized reading' (duration: 731.573594ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.751935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:03.020283Z","time spent":"731.646356ms","remote":"127.0.0.1:45004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-01-19T16:12:03.752127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.943946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.752154Z","caller":"traceutil/trace.go:171","msg":"trace[228739059] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:572; }","duration":"731.980907ms","start":"2026-01-19T16:12:03.020167Z","end":"2026-01-19T16:12:03.752148Z","steps":["trace[228739059] 'agreement among raft nodes before linearized reading' (duration: 731.939746ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.752174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:03.020163Z","time spent":"732.006158ms","remote":"127.0.0.1:45004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"warn","ts":"2026-01-19T16:12:03.752272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.118221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.752302Z","caller":"traceutil/trace.go:171","msg":"trace[390422921] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:572; }","duration":"732.167153ms","start":"2026-01-19T16:12:03.020128Z","end":"2026-01-19T16:12:03.752295Z","steps":["trace[390422921] 'agreement among raft nodes before linearized reading' (duration: 732.114181ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.752321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:03.020105Z","time spent":"732.211605ms","remote":"127.0.0.1:45004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:12:03.752422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.744981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.752448Z","caller":"traceutil/trace.go:171","msg":"trace[1953064654] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:572; }","duration":"745.785642ms","start":"2026-01-19T16:12:03.006655Z","end":"2026-01-19T16:12:03.752441Z","steps":["trace[1953064654] 'agreement among raft nodes before linearized reading' (duration: 745.74324ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.752473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:03.006579Z","time spent":"745.888896ms","remote":"127.0.0.1:44990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-01-19T16:12:03.752576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.187345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.752601Z","caller":"traceutil/trace.go:171","msg":"trace[1439464979] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:572; }","duration":"746.223286ms","start":"2026-01-19T16:12:03.006371Z","end":"2026-01-19T16:12:03.752594Z","steps":["trace[1439464979] 'agreement among raft nodes before linearized reading' (duration: 746.186074ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.752626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:03.006346Z","time spent":"746.274547ms","remote":"127.0.0.1:44990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:12:03.752703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.65754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.752727Z","caller":"traceutil/trace.go:171","msg":"trace[1338938564] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:572; }","duration":"746.697061ms","start":"2026-01-19T16:12:03.006025Z","end":"2026-01-19T16:12:03.752722Z","steps":["trace[1338938564] 'agreement among raft nodes before linearized reading' (duration: 746.66163ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.752749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:03.006012Z","time spent":"746.731372ms","remote":"127.0.0.1:44990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"warn","ts":"2026-01-19T16:12:03.752836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.890477ms","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-01-19T16:12:03.752864Z","caller":"traceutil/trace.go:171","msg":"trace[954796928] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"755.924958ms","start":"2026-01-19T16:12:02.99693Z","end":"2026-01-19T16:12:03.752855Z","steps":["trace[954796928] 'agreement among raft nodes before linearized reading' (duration: 755.886917ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.754305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.99691Z","time spent":"755.96768ms","remote":"127.0.0.1:44974","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-01-19T16:12:03.754029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.216111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.756647Z","caller":"traceutil/trace.go:171","msg":"trace[1068263402] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"805.844787ms","start":"2026-01-19T16:12:02.950788Z","end":"2026-01-19T16:12:03.756633Z","steps":["trace[1068263402] 'agreement among raft nodes before linearized reading' (duration: 803.200502ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.756721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.950785Z","time spent":"805.917879ms","remote":"127.0.0.1:44940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-01-19T16:12:03.754486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.620063ms","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-01-19T16:12:03.756981Z","caller":"traceutil/trace.go:171","msg":"trace[97815277] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"760.138415ms","start":"2026-01-19T16:12:02.996833Z","end":"2026-01-19T16:12:03.756972Z","steps":["trace[97815277] 'agreement among raft nodes before linearized reading' (duration: 757.622694ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.757024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.996828Z","time spent":"760.177936ms","remote":"127.0.0.1:44974","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-01-19T16:12:03.754558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.760528ms","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-01-19T16:12:03.757286Z","caller":"traceutil/trace.go:171","msg":"trace[1919875417] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"760.525456ms","start":"2026-01-19T16:12:02.996754Z","end":"2026-01-19T16:12:03.757279Z","steps":["trace[1919875417] 'agreement among raft nodes before linearized reading' (duration: 757.751027ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.757343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.996736Z","time spent":"760.588919ms","remote":"127.0.0.1:44974","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-01-19T16:12:03.754605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.786414ms","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-01-19T16:12:03.760234Z","caller":"traceutil/trace.go:171","msg":"trace[2060333010] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"779.401915ms","start":"2026-01-19T16:12:02.980801Z","end":"2026-01-19T16:12:03.760203Z","steps":["trace[2060333010] 'agreement among raft nodes before linearized reading' (duration: 773.782714ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.760281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.980797Z","time spent":"779.471557ms","remote":"127.0.0.1:44966","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-01-19T16:12:03.754665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.869766ms","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-01-19T16:12:03.760925Z","caller":"traceutil/trace.go:171","msg":"trace[1378614033] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"780.154409ms","start":"2026-01-19T16:12:02.980762Z","end":"2026-01-19T16:12:03.760917Z","steps":["trace[1378614033] 'agreement among raft nodes before linearized reading' (duration: 773.885707ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.760975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.980758Z","time spent":"780.20777ms","remote":"127.0.0.1:44966","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-01-19T16:12:03.754691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.957799ms","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-01-19T16:12:03.761585Z","caller":"traceutil/trace.go:171","msg":"trace[1968564995] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"780.730028ms","start":"2026-01-19T16:12:02.980711Z","end":"2026-01-19T16:12:03.761441Z","steps":["trace[1968564995] 'agreement among raft nodes before linearized reading' (duration: 773.96569ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.761644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.980692Z","time spent":"780.941625ms","remote":"127.0.0.1:44966","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-01-19T16:12:03.754717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.95699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.763058Z","caller":"traceutil/trace.go:171","msg":"trace[272851215] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:572; }","duration":"796.30291ms","start":"2026-01-19T16:12:02.966747Z","end":"2026-01-19T16:12:03.76305Z","steps":["trace[272851215] 'agreement among raft nodes before linearized reading' (duration: 787.95546ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.763216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.966048Z","time spent":"797.158307ms","remote":"127.0.0.1:44950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-01-19T16:12:03.754745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.613712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.76443Z","caller":"traceutil/trace.go:171","msg":"trace[21654177] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:572; }","duration":"798.276503ms","start":"2026-01-19T16:12:02.96612Z","end":"2026-01-19T16:12:03.764396Z","steps":["trace[21654177] 'agreement among raft nodes before linearized reading' (duration: 788.611271ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.764473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.966115Z","time spent":"798.348825ms","remote":"127.0.0.1:44950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-01-19T16:12:03.75559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191327983s","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-01-19T16:12:03.765009Z","caller":"traceutil/trace.go:171","msg":"trace[816920503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"1.200774747s","start":"2026-01-19T16:12:02.564225Z","end":"2026-01-19T16:12:03.765Z","steps":["trace[816920503] 'agreement among raft nodes before linearized reading' (duration: 1.191302122s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.765183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.564208Z","time spent":"1.200967284s","remote":"127.0.0.1:37702","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-01-19T16:12:03.755626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.837784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.765899Z","caller":"traceutil/trace.go:171","msg":"trace[2031338756] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"815.115605ms","start":"2026-01-19T16:12:02.950775Z","end":"2026-01-19T16:12:03.765891Z","steps":["trace[2031338756] 'agreement among raft nodes before linearized reading' (duration: 804.832874ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.765948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.95077Z","time spent":"815.167317ms","remote":"127.0.0.1:44940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:12:03.755655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.893616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.766352Z","caller":"traceutil/trace.go:171","msg":"trace[1555249974] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"815.620812ms","start":"2026-01-19T16:12:02.950723Z","end":"2026-01-19T16:12:03.766343Z","steps":["trace[1555249974] 'agreement among raft nodes before linearized reading' (duration: 804.915167ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.766519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.950701Z","time spent":"815.806748ms","remote":"127.0.0.1:44940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-01-19T16:12:03.755711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.979665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-01-19T16:12:03.767148Z","caller":"traceutil/trace.go:171","msg":"trace[689450234] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"873.462874ms","start":"2026-01-19T16:12:02.893675Z","end":"2026-01-19T16:12:03.767138Z","steps":["trace[689450234] 'agreement among raft nodes before linearized reading' (duration: 861.991295ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.767315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.89365Z","time spent":"873.65184ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:12:03.755763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.038492ms","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-01-19T16:12:03.768518Z","caller":"traceutil/trace.go:171","msg":"trace[102748828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"990.721502ms","start":"2026-01-19T16:12:02.777674Z","end":"2026-01-19T16:12:03.768395Z","steps":["trace[102748828] 'agreement among raft nodes before linearized reading' (duration: 978.049613ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.768611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.777644Z","time spent":"990.956479ms","remote":"127.0.0.1:37702","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-01-19T16:12:03.755812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063276348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-01-19T16:12:03.769443Z","caller":"traceutil/trace.go:171","msg":"trace[1580356171] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"1.076938478s","start":"2026-01-19T16:12:02.692495Z","end":"2026-01-19T16:12:03.769434Z","steps":["trace[1580356171] 'agreement among raft nodes before linearized reading' (duration: 1.063278198s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.769591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.692456Z","time spent":"1.077029522s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:12:03.75584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146715186s","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-01-19T16:12:03.776124Z","caller":"traceutil/trace.go:171","msg":"trace[631329843] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:572; }","duration":"1.166996849s","start":"2026-01-19T16:12:02.609111Z","end":"2026-01-19T16:12:03.776108Z","steps":["trace[631329843] 'agreement among raft nodes before linearized reading' (duration: 1.146711486s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.776172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.609107Z","time spent":"1.16704555s","remote":"127.0.0.1:44930","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":"warn","ts":"2026-01-19T16:12:03.755869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146949324s","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-01-19T16:12:03.776328Z","caller":"traceutil/trace.go:171","msg":"trace[788346590] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:572; }","duration":"1.167417023s","start":"2026-01-19T16:12:02.608905Z","end":"2026-01-19T16:12:03.776322Z","steps":["trace[788346590] 'agreement among raft nodes before linearized reading' (duration: 1.146946923s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.776348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.608901Z","time spent":"1.167438503s","remote":"127.0.0.1:44930","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-01-19T16:12:03.755897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147179431s","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-01-19T16:12:03.77648Z","caller":"traceutil/trace.go:171","msg":"trace[1394150599] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:572; }","duration":"1.167780845s","start":"2026-01-19T16:12:02.60869Z","end":"2026-01-19T16:12:03.776471Z","steps":["trace[1394150599] 'agreement among raft nodes before linearized reading' (duration: 1.147190581s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.776498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.608678Z","time spent":"1.167813995s","remote":"127.0.0.1:44930","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-01-19T16:12:03.755982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159918491s","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-01-19T16:12:03.776588Z","caller":"traceutil/trace.go:171","msg":"trace[655945310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"1.180583497s","start":"2026-01-19T16:12:02.595998Z","end":"2026-01-19T16:12:03.776582Z","steps":["trace[655945310] 'agreement among raft nodes before linearized reading' (duration: 1.159910361s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.776604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.595985Z","time spent":"1.180614398s","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T16:12:03.756112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263791408s","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-01-19T16:12:03.776718Z","caller":"traceutil/trace.go:171","msg":"trace[1781719505] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"1.284438012s","start":"2026-01-19T16:12:02.492274Z","end":"2026-01-19T16:12:03.776712Z","steps":["trace[1781719505] 'agreement among raft nodes before linearized reading' (duration: 1.263769257s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.776735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.492253Z","time spent":"1.284476293s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:12:03.756177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464149391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-01-19T16:12:03.776846Z","caller":"traceutil/trace.go:171","msg":"trace[162720349] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"1.484859788s","start":"2026-01-19T16:12:02.291978Z","end":"2026-01-19T16:12:03.776837Z","steps":["trace[162720349] 'agreement among raft nodes before linearized reading' (duration: 1.464148691s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.776867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.291957Z","time spent":"1.484903469s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:12:03.75623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597331191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-19T16:12:03.784354Z","caller":"traceutil/trace.go:171","msg":"trace[1812096292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:572; }","duration":"1.625476917s","start":"2026-01-19T16:12:02.158865Z","end":"2026-01-19T16:12:03.784342Z","steps":["trace[1812096292] 'agreement among raft nodes before linearized reading' (duration: 1.59732579s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.784407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:02.158846Z","time spent":"1.62554823s","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T16:12:03.756256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.863190083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:03.787749Z","caller":"traceutil/trace.go:171","msg":"trace[855824959] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:572; }","duration":"1.894706789s","start":"2026-01-19T16:12:01.893034Z","end":"2026-01-19T16:12:03.787741Z","steps":["trace[855824959] 'agreement among raft nodes before linearized reading' (duration: 1.863207954s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:03.787833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:01.893017Z","time spent":"1.894806151s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-01-19T16:12:04.578985Z","caller":"traceutil/trace.go:171","msg":"trace[2018322543] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"126.727642ms","start":"2026-01-19T16:12:04.452231Z","end":"2026-01-19T16:12:04.578959Z","steps":["trace[2018322543] 'process raft request' (duration: 125.944937ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:04.60156Z","caller":"traceutil/trace.go:171","msg":"trace[1021226291] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"108.94094ms","start":"2026-01-19T16:12:04.492595Z","end":"2026-01-19T16:12:04.601536Z","steps":["trace[1021226291] 'read index received' (duration: 97.317315ms)","trace[1021226291] 'applied index is now lower than readState.Index' (duration: 11.622805ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:12:04.601738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.119455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-01-19T16:12:04.601759Z","caller":"traceutil/trace.go:171","msg":"trace[51735233] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:585; }","duration":"109.181237ms","start":"2026-01-19T16:12:04.49257Z","end":"2026-01-19T16:12:04.601751Z","steps":["trace[51735233] 'agreement among raft nodes before linearized reading' (duration: 109.044512ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:04.601895Z","caller":"traceutil/trace.go:171","msg":"trace[1478314165] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"128.454558ms","start":"2026-01-19T16:12:04.473428Z","end":"2026-01-19T16:12:04.601882Z","steps":["trace[1478314165] 'process raft request' (duration: 121.758082ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:06.975141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.3442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-19T16:12:06.97522Z","caller":"traceutil/trace.go:171","msg":"trace[1252143289] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:594; }","duration":"208.471115ms","start":"2026-01-19T16:12:06.766727Z","end":"2026-01-19T16:12:06.975198Z","steps":["trace[1252143289] 'range keys from in-memory index tree' (duration: 208.230477ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:06.976409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.265531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:06.976441Z","caller":"traceutil/trace.go:171","msg":"trace[1714326002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"363.360714ms","start":"2026-01-19T16:12:06.61307Z","end":"2026-01-19T16:12:06.976431Z","steps":["trace[1714326002] 'range keys from in-memory index tree' (duration: 363.182088ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:06.976457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:06.613047Z","time spent":"363.405265ms","remote":"127.0.0.1:37492","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-01-19T16:12:10.169947Z","caller":"traceutil/trace.go:171","msg":"trace[245308716] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"279.423351ms","start":"2026-01-19T16:12:09.890505Z","end":"2026-01-19T16:12:10.169928Z","steps":["trace[245308716] 'read index received' (duration: 279.188443ms)","trace[245308716] 'applied index is now lower than readState.Index' (duration: 233.858µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:12:10.170366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.832583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T16:12:10.170994Z","caller":"traceutil/trace.go:171","msg":"trace[202256925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:601; }","duration":"280.501646ms","start":"2026-01-19T16:12:09.89045Z","end":"2026-01-19T16:12:10.170952Z","steps":["trace[202256925] 'agreement among raft nodes before linearized reading' (duration: 279.746821ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:10.170484Z","caller":"traceutil/trace.go:171","msg":"trace[1313244761] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"302.219114ms","start":"2026-01-19T16:12:09.868256Z","end":"2026-01-19T16:12:10.170476Z","steps":["trace[1313244761] 'process raft request' (duration: 301.520722ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:10.171769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:09.868243Z","time spent":"303.443434ms","remote":"127.0.0.1:37702","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-01-19T16:12:10.172397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.878797ms","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-01-19T16:12:10.172432Z","caller":"traceutil/trace.go:171","msg":"trace[448699710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"280.921539ms","start":"2026-01-19T16:12:09.891502Z","end":"2026-01-19T16:12:10.172423Z","steps":["trace[448699710] 'agreement among raft nodes before linearized reading' (duration: 280.770964ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:10.172784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.940688ms","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-01-19T16:12:10.172804Z","caller":"traceutil/trace.go:171","msg":"trace[791984628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"280.001059ms","start":"2026-01-19T16:12:09.892794Z","end":"2026-01-19T16:12:10.172795Z","steps":["trace[791984628] 'agreement among raft nodes before linearized reading' (duration: 279.955178ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:20.256964Z","caller":"traceutil/trace.go:171","msg":"trace[502441796] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"168.506314ms","start":"2026-01-19T16:12:20.088413Z","end":"2026-01-19T16:12:20.256919Z","steps":["trace[502441796] 'process raft request' (duration: 168.310268ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:22.795629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054428563,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:12:23.296729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054428563,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:12:23.441376Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.350907865s","expected-duration":"1s"} {"level":"info","ts":"2026-01-19T16:12:23.467385Z","caller":"traceutil/trace.go:171","msg":"trace[561917230] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"1.37699383s","start":"2026-01-19T16:12:22.090361Z","end":"2026-01-19T16:12:23.467355Z","steps":["trace[561917230] 'process raft request' (duration: 1.351223695s)","trace[561917230] 'compare' (duration: 25.562529ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:12:23.467539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:22.09033Z","time spent":"1.377122394s","remote":"127.0.0.1:37646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:12:23.654054Z","caller":"traceutil/trace.go:171","msg":"trace[195874896] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"1.358506198s","start":"2026-01-19T16:12:22.295507Z","end":"2026-01-19T16:12:23.654013Z","steps":["trace[195874896] 'read index received' (duration: 1.14614083s)","trace[195874896] 'applied index is now lower than readState.Index' (duration: 212.363178ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:12:23.654287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358736915s","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-01-19T16:12:23.65546Z","caller":"traceutil/trace.go:171","msg":"trace[1765949812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:672; }","duration":"1.359972874s","start":"2026-01-19T16:12:22.295475Z","end":"2026-01-19T16:12:23.655448Z","steps":["trace[1765949812] 'agreement among raft nodes before linearized reading' (duration: 1.358642632s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:23.654453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278363803s","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-01-19T16:12:23.655515Z","caller":"traceutil/trace.go:171","msg":"trace[1883741697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:672; }","duration":"1.279448996s","start":"2026-01-19T16:12:22.376059Z","end":"2026-01-19T16:12:23.655508Z","steps":["trace[1883741697] 'agreement among raft nodes before linearized reading' (duration: 1.278346971s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:23.655532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:22.376044Z","time spent":"1.279483398s","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T16:12:23.654832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.07637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bm9g5\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-01-19T16:12:23.655715Z","caller":"traceutil/trace.go:171","msg":"trace[1228021143] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bm9g5; range_end:; response_count:1; response_revision:672; }","duration":"178.987809ms","start":"2026-01-19T16:12:23.476719Z","end":"2026-01-19T16:12:23.655707Z","steps":["trace[1228021143] 'agreement among raft nodes before linearized reading' (duration: 178.055529ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:23.654864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043533455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:23.656017Z","caller":"traceutil/trace.go:171","msg":"trace[902177595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"1.044722932s","start":"2026-01-19T16:12:22.611287Z","end":"2026-01-19T16:12:23.65601Z","steps":["trace[902177595] 'agreement among raft nodes before linearized reading' (duration: 1.043560875s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:23.656045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:22.61127Z","time spent":"1.044767674s","remote":"127.0.0.1:37492","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-01-19T16:12:23.654913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177857194s","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-01-19T16:12:23.656366Z","caller":"traceutil/trace.go:171","msg":"trace[1903289813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"1.179342803s","start":"2026-01-19T16:12:22.477015Z","end":"2026-01-19T16:12:23.656358Z","steps":["trace[1903289813] 'agreement among raft nodes before linearized reading' (duration: 1.177859995s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:23.656393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:22.476995Z","time spent":"1.179390673s","remote":"127.0.0.1:37702","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-01-19T16:12:23.654962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243275009s","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-01-19T16:12:23.657497Z","caller":"traceutil/trace.go:171","msg":"trace[1522480005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:672; }","duration":"1.24581196s","start":"2026-01-19T16:12:22.411676Z","end":"2026-01-19T16:12:23.657488Z","steps":["trace[1522480005] 'agreement among raft nodes before linearized reading' (duration: 1.243248898s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:23.657527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:22.411673Z","time spent":"1.245845831s","remote":"127.0.0.1:37702","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-01-19T16:12:23.65501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243342041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T16:12:23.657666Z","caller":"traceutil/trace.go:171","msg":"trace[217154809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:672; }","duration":"1.246028837s","start":"2026-01-19T16:12:22.411629Z","end":"2026-01-19T16:12:23.657658Z","steps":["trace[217154809] 'agreement among raft nodes before linearized reading' (duration: 1.243342321s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:23.657692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:22.411612Z","time spent":"1.246072588s","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T16:12:23.655488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:22.295456Z","time spent":"1.360022496s","remote":"127.0.0.1:37702","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-01-19T16:12:24.013795Z","caller":"traceutil/trace.go:171","msg":"trace[943423135] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:695; }","duration":"327.0801ms","start":"2026-01-19T16:12:23.686695Z","end":"2026-01-19T16:12:24.013776Z","steps":["trace[943423135] 'read index received' (duration: 60.423284ms)","trace[943423135] 'applied index is now lower than readState.Index' (duration: 266.655656ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:12:24.013915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.202024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:24.013962Z","caller":"traceutil/trace.go:171","msg":"trace[928075593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"327.293307ms","start":"2026-01-19T16:12:23.68666Z","end":"2026-01-19T16:12:24.013954Z","steps":["trace[928075593] 'agreement among raft nodes before linearized reading' (duration: 327.198384ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:24.013991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:23.686644Z","time spent":"327.341039ms","remote":"127.0.0.1:37492","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-01-19T16:12:24.0147Z","caller":"traceutil/trace.go:171","msg":"trace[109193591] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"335.48962ms","start":"2026-01-19T16:12:23.679173Z","end":"2026-01-19T16:12:24.014663Z","steps":["trace[109193591] 'process raft request' (duration: 334.345963ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:24.014857Z","caller":"traceutil/trace.go:171","msg":"trace[2137318371] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"326.46938ms","start":"2026-01-19T16:12:23.688376Z","end":"2026-01-19T16:12:24.014845Z","steps":["trace[2137318371] 'process raft request' (duration: 325.331994ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:24.014886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:23.679145Z","time spent":"335.655435ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:12:24.014945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:23.688362Z","time spent":"326.517853ms","remote":"127.0.0.1:37646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:12:24.014981Z","caller":"traceutil/trace.go:171","msg":"trace[1378611678] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"315.233422ms","start":"2026-01-19T16:12:23.699741Z","end":"2026-01-19T16:12:24.014975Z","steps":["trace[1378611678] 'process raft request' (duration: 314.004292ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:24.015008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:23.699718Z","time spent":"315.273202ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:12:24.015054Z","caller":"traceutil/trace.go:171","msg":"trace[1833625787] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"335.779359ms","start":"2026-01-19T16:12:23.679267Z","end":"2026-01-19T16:12:24.015046Z","steps":["trace[1833625787] 'process raft request' (duration: 334.396985ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:24.015097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:23.679251Z","time spent":"335.816631ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:12:24.015143Z","caller":"traceutil/trace.go:171","msg":"trace[1169584725] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"335.837881ms","start":"2026-01-19T16:12:23.679301Z","end":"2026-01-19T16:12:24.015139Z","steps":["trace[1169584725] 'process raft request' (duration: 334.383754ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:24.015189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:23.679296Z","time spent":"335.878432ms","remote":"127.0.0.1:37702","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-01-19T16:12:24.232624Z","caller":"traceutil/trace.go:171","msg":"trace[842788448] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"202.600216ms","start":"2026-01-19T16:12:24.029998Z","end":"2026-01-19T16:12:24.232598Z","steps":["trace[842788448] 'process raft request' (duration: 199.470476ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:24.240279Z","caller":"traceutil/trace.go:171","msg":"trace[1264046838] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"203.155463ms","start":"2026-01-19T16:12:24.037107Z","end":"2026-01-19T16:12:24.240262Z","steps":["trace[1264046838] 'process raft request' (duration: 203.04618ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:34.013112Z","caller":"traceutil/trace.go:171","msg":"trace[312550655] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"136.80533ms","start":"2026-01-19T16:12:33.87628Z","end":"2026-01-19T16:12:34.013086Z","steps":["trace[312550655] 'read index received' (duration: 136.616164ms)","trace[312550655] 'applied index is now lower than readState.Index' (duration: 188.636µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:12:34.013572Z","caller":"traceutil/trace.go:171","msg":"trace[1856414723] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"221.970516ms","start":"2026-01-19T16:12:33.791587Z","end":"2026-01-19T16:12:34.013558Z","steps":["trace[1856414723] 'process raft request' (duration: 221.368567ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:34.017101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.4544ms","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-01-19T16:12:34.017142Z","caller":"traceutil/trace.go:171","msg":"trace[590588015] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:752; }","duration":"140.873759ms","start":"2026-01-19T16:12:33.876255Z","end":"2026-01-19T16:12:34.017129Z","steps":["trace[590588015] 'agreement among raft nodes before linearized reading' (duration: 137.45238ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:34.017252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.90753ms","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-01-19T16:12:34.01727Z","caller":"traceutil/trace.go:171","msg":"trace[814993244] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:752; }","duration":"140.955403ms","start":"2026-01-19T16:12:33.87631Z","end":"2026-01-19T16:12:34.017265Z","steps":["trace[814993244] 'agreement among raft nodes before linearized reading' (duration: 140.898481ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:34.017548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.887109ms","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-01-19T16:12:34.017592Z","caller":"traceutil/trace.go:171","msg":"trace[1315234705] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:752; }","duration":"139.94301ms","start":"2026-01-19T16:12:33.87764Z","end":"2026-01-19T16:12:34.017583Z","steps":["trace[1315234705] 'agreement among raft nodes before linearized reading' (duration: 139.883828ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:35.42088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054428721,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:12:35.921754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054428721,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:12:36.422343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054428721,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:12:36.791407Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.201539145s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-19T16:12:36.841512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.764994581s","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-01-19T16:12:36.841628Z","caller":"traceutil/trace.go:171","msg":"trace[287870939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"2.765175346s","start":"2026-01-19T16:12:34.076436Z","end":"2026-01-19T16:12:36.841611Z","steps":["trace[287870939] 'range keys from in-memory index tree' (duration: 2.764197385s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:36.841663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:34.076413Z","time spent":"2.765243369s","remote":"127.0.0.1:37702","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-01-19T16:12:36.841978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.746300702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-19T16:12:36.841993Z","caller":"traceutil/trace.go:171","msg":"trace[930053151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:752; }","duration":"2.746351694s","start":"2026-01-19T16:12:34.095636Z","end":"2026-01-19T16:12:36.841988Z","steps":["trace[930053151] 'range keys from in-memory index tree' (duration: 2.746104266s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:36.842005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:34.095595Z","time spent":"2.746407116s","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T16:12:36.842111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.738917196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T16:12:36.842127Z","caller":"traceutil/trace.go:171","msg":"trace[426975701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"2.738953557s","start":"2026-01-19T16:12:34.103166Z","end":"2026-01-19T16:12:36.84212Z","steps":["trace[426975701] 'range keys from in-memory index tree' (duration: 2.738832243s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:36.842138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:34.103153Z","time spent":"2.738982488s","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T16:12:36.842239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.735109114s","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-01-19T16:12:36.842252Z","caller":"traceutil/trace.go:171","msg":"trace[1202806282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:752; }","duration":"2.735144335s","start":"2026-01-19T16:12:34.107104Z","end":"2026-01-19T16:12:36.842248Z","steps":["trace[1202806282] 'range keys from in-memory index tree' (duration: 2.735010111s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:36.842288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:34.107071Z","time spent":"2.735213727s","remote":"127.0.0.1:37702","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-01-19T16:12:36.842449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.655836376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:36.842466Z","caller":"traceutil/trace.go:171","msg":"trace[444634686] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:752; }","duration":"2.655912569s","start":"2026-01-19T16:12:34.186549Z","end":"2026-01-19T16:12:36.842462Z","steps":["trace[444634686] 'count revisions from in-memory index tree' (duration: 2.655745084s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:36.842489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:34.186523Z","time spent":"2.65596145s","remote":"127.0.0.1:37740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:12:36.842907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.54941982s","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-01-19T16:12:36.842985Z","caller":"traceutil/trace.go:171","msg":"trace[304420023] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:752; }","duration":"2.549555764s","start":"2026-01-19T16:12:34.29341Z","end":"2026-01-19T16:12:36.842966Z","steps":["trace[304420023] 'count revisions from in-memory index tree' (duration: 2.549337597s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:36.843023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:34.293389Z","time spent":"2.549624376s","remote":"127.0.0.1:37960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":98,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-01-19T16:12:36.843732Z","caller":"traceutil/trace.go:171","msg":"trace[191600347] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"2.253960902s","start":"2026-01-19T16:12:34.589747Z","end":"2026-01-19T16:12:36.843708Z","steps":["trace[191600347] 'process raft request' (duration: 2.201889245s)","trace[191600347] 'compare' (duration: 49.005359ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:12:36.843805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:34.589723Z","time spent":"2.254031805s","remote":"127.0.0.1:37562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:12:36.888089Z","caller":"traceutil/trace.go:171","msg":"trace[1148831313] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:779; }","duration":"1.967251144s","start":"2026-01-19T16:12:34.920815Z","end":"2026-01-19T16:12:36.888066Z","steps":["trace[1148831313] 'read index received' (duration: 1.870832358s)","trace[1148831313] 'applied index is now lower than readState.Index' (duration: 96.417356ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:12:36.888196Z","caller":"traceutil/trace.go:171","msg":"trace[370514164] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.052417909s","start":"2026-01-19T16:12:35.835769Z","end":"2026-01-19T16:12:36.888187Z","steps":["trace[370514164] 'process raft request' (duration: 1.052144861s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:36.88831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:35.835744Z","time spent":"1.05247434s","remote":"127.0.0.1:37702","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-01-19T16:12:36.888393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.967561195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:12:36.88847Z","caller":"traceutil/trace.go:171","msg":"trace[640347727] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:754; }","duration":"1.967701059s","start":"2026-01-19T16:12:34.920752Z","end":"2026-01-19T16:12:36.888453Z","steps":["trace[640347727] 'agreement among raft nodes before linearized reading' (duration: 1.967576355s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:36.888504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:34.920718Z","time spent":"1.967779161s","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:12:37.34704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.32372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:12:37.347227Z","caller":"traceutil/trace.go:171","msg":"trace[1351684084] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"497.070162ms","start":"2026-01-19T16:12:36.850126Z","end":"2026-01-19T16:12:37.347196Z","steps":["trace[1351684084] 'process raft request' (duration: 367.477033ms)","trace[1351684084] 'compare' (duration: 129.177206ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:12:37.347278Z","caller":"traceutil/trace.go:171","msg":"trace[992014108] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:781; }","duration":"459.103047ms","start":"2026-01-19T16:12:36.888156Z","end":"2026-01-19T16:12:37.347259Z","steps":["trace[992014108] 'read index received' (duration: 329.380084ms)","trace[992014108] 'applied index is now lower than readState.Index' (duration: 129.721633ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:12:37.347378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732293722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-19T16:12:37.347387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:36.850108Z","time spent":"497.185866ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:12:37.347415Z","caller":"traceutil/trace.go:171","msg":"trace[1934106715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"1.732356975s","start":"2026-01-19T16:12:35.615048Z","end":"2026-01-19T16:12:37.347405Z","steps":["trace[1934106715] 'agreement among raft nodes before linearized reading' (duration: 1.732264442s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:37.34745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:35.615028Z","time spent":"1.732413857s","remote":"127.0.0.1:37492","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-01-19T16:12:37.3477Z","caller":"traceutil/trace.go:171","msg":"trace[586443832] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"494.039835ms","start":"2026-01-19T16:12:36.853651Z","end":"2026-01-19T16:12:37.347691Z","steps":["trace[586443832] 'process raft request' (duration: 493.56676ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:37.347775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:36.853623Z","time spent":"494.098567ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:12:37.34783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.84527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-19T16:12:37.347855Z","caller":"traceutil/trace.go:171","msg":"trace[245624529] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:758; }","duration":"578.923473ms","start":"2026-01-19T16:12:36.768925Z","end":"2026-01-19T16:12:37.347848Z","steps":["trace[245624529] 'agreement among raft nodes before linearized reading' (duration: 578.82332ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:37.347873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:36.768897Z","time spent":"578.971933ms","remote":"127.0.0.1:37514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.124\" "} {"level":"info","ts":"2026-01-19T16:12:37.347903Z","caller":"traceutil/trace.go:171","msg":"trace[10620259] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"495.293605ms","start":"2026-01-19T16:12:36.852587Z","end":"2026-01-19T16:12:37.34788Z","steps":["trace[10620259] 'process raft request' (duration: 494.540021ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:37.347948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:36.852579Z","time spent":"495.341877ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:12:37.348003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31670414s","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-01-19T16:12:37.348026Z","caller":"traceutil/trace.go:171","msg":"trace[1571370224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"1.316762091s","start":"2026-01-19T16:12:36.031257Z","end":"2026-01-19T16:12:37.348019Z","steps":["trace[1571370224] 'agreement among raft nodes before linearized reading' (duration: 1.316700689s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:37.348045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:36.031233Z","time spent":"1.316806413s","remote":"127.0.0.1:37702","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":"info","ts":"2026-01-19T16:12:37.348049Z","caller":"traceutil/trace.go:171","msg":"trace[765696765] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"495.410489ms","start":"2026-01-19T16:12:36.852628Z","end":"2026-01-19T16:12:37.348039Z","steps":["trace[765696765] 'process raft request' (duration: 494.554872ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:37.348088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:36.852624Z","time spent":"495.44272ms","remote":"127.0.0.1:37702","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-01-19T16:12:41.802597Z","caller":"traceutil/trace.go:171","msg":"trace[84434258] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"424.431336ms","start":"2026-01-19T16:12:41.378138Z","end":"2026-01-19T16:12:41.802569Z","steps":["trace[84434258] 'read index received' (duration: 424.230849ms)","trace[84434258] 'applied index is now lower than readState.Index' (duration: 199.647µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:12:41.802943Z","caller":"traceutil/trace.go:171","msg":"trace[1876561067] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"429.291361ms","start":"2026-01-19T16:12:41.373636Z","end":"2026-01-19T16:12:41.802928Z","steps":["trace[1876561067] 'process raft request' (duration: 428.798885ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:41.803042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:41.373623Z","time spent":"429.349063ms","remote":"127.0.0.1:37702","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-01-19T16:12:41.803068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.971097ms","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-01-19T16:12:41.803096Z","caller":"traceutil/trace.go:171","msg":"trace[1349456547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"422.013608ms","start":"2026-01-19T16:12:41.381074Z","end":"2026-01-19T16:12:41.803088Z","steps":["trace[1349456547] 'agreement among raft nodes before linearized reading' (duration: 421.885044ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:41.803115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:41.381069Z","time spent":"422.041899ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T16:12:41.803198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.059416ms","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-01-19T16:12:41.803214Z","caller":"traceutil/trace.go:171","msg":"trace[1694953657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"425.084347ms","start":"2026-01-19T16:12:41.378124Z","end":"2026-01-19T16:12:41.803209Z","steps":["trace[1694953657] 'agreement among raft nodes before linearized reading' (duration: 425.034965ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:41.80323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:41.378118Z","time spent":"425.107448ms","remote":"127.0.0.1:37702","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-01-19T16:12:41.803318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.665362ms","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-01-19T16:12:41.803342Z","caller":"traceutil/trace.go:171","msg":"trace[945667553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"344.713464ms","start":"2026-01-19T16:12:41.458622Z","end":"2026-01-19T16:12:41.803336Z","steps":["trace[945667553] 'agreement among raft nodes before linearized reading' (duration: 344.649592ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:41.80336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:41.458609Z","time spent":"344.746545ms","remote":"127.0.0.1:37702","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-01-19T16:12:41.803364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.319706ms","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-01-19T16:12:41.803383Z","caller":"traceutil/trace.go:171","msg":"trace[249536487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"421.348497ms","start":"2026-01-19T16:12:41.38203Z","end":"2026-01-19T16:12:41.803378Z","steps":["trace[249536487] 'agreement among raft nodes before linearized reading' (duration: 421.283045ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:41.803402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:41.382025Z","time spent":"421.370798ms","remote":"127.0.0.1:37702","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-01-19T16:12:42.115264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.404085ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-19T16:12:56.408078Z","caller":"traceutil/trace.go:171","msg":"trace[1871427064] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"446.389579ms","start":"2026-01-19T16:12:55.961657Z","end":"2026-01-19T16:12:56.408047Z","steps":["trace[1871427064] 'read index received' (duration: 438.420044ms)","trace[1871427064] 'applied index is now lower than readState.Index' (duration: 7.963985ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:12:56.408538Z","caller":"traceutil/trace.go:171","msg":"trace[782149832] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"452.925948ms","start":"2026-01-19T16:12:55.955598Z","end":"2026-01-19T16:12:56.408524Z","steps":["trace[782149832] 'process raft request' (duration: 444.56084ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:56.408779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:55.955578Z","time spent":"453.029182ms","remote":"127.0.0.1:37702","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-01-19T16:12:56.409831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.145315ms","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-01-19T16:12:56.410038Z","caller":"traceutil/trace.go:171","msg":"trace[1217010014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"448.350292ms","start":"2026-01-19T16:12:55.961636Z","end":"2026-01-19T16:12:56.409986Z","steps":["trace[1217010014] 'agreement among raft nodes before linearized reading' (duration: 448.010441ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:56.410139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:55.961625Z","time spent":"448.499256ms","remote":"127.0.0.1:37702","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-01-19T16:12:56.410873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.257212ms","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":"info","ts":"2026-01-19T16:12:56.411967Z","caller":"traceutil/trace.go:171","msg":"trace[209504105] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:811; }","duration":"227.589095ms","start":"2026-01-19T16:12:56.184363Z","end":"2026-01-19T16:12:56.411952Z","steps":["trace[209504105] 'agreement among raft nodes before linearized reading' (duration: 226.448099ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:56.411895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.400518ms","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-01-19T16:12:56.412617Z","caller":"traceutil/trace.go:171","msg":"trace[1679019954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:811; }","duration":"436.201333ms","start":"2026-01-19T16:12:55.976406Z","end":"2026-01-19T16:12:56.412608Z","steps":["trace[1679019954] 'agreement among raft nodes before linearized reading' (duration: 434.639823ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:56.412662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:55.976377Z","time spent":"436.275216ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T16:12:56.415207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.90645ms","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-01-19T16:12:56.415434Z","caller":"traceutil/trace.go:171","msg":"trace[1204396142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:811; }","duration":"439.017993ms","start":"2026-01-19T16:12:55.976236Z","end":"2026-01-19T16:12:56.415254Z","steps":["trace[1204396142] 'agreement among raft nodes before linearized reading' (duration: 434.378164ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:12:56.4157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:12:55.976224Z","time spent":"439.288872ms","remote":"127.0.0.1:37702","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":"info","ts":"2026-01-19T16:12:56.632929Z","caller":"traceutil/trace.go:171","msg":"trace[1267429659] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"215.068915ms","start":"2026-01-19T16:12:56.417838Z","end":"2026-01-19T16:12:56.632907Z","steps":["trace[1267429659] 'process raft request' (duration: 205.52377ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:56.664626Z","caller":"traceutil/trace.go:171","msg":"trace[855362726] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"246.438539ms","start":"2026-01-19T16:12:56.418173Z","end":"2026-01-19T16:12:56.664612Z","steps":["trace[855362726] 'process raft request' (duration: 245.817329ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:12:56.664524Z","caller":"traceutil/trace.go:171","msg":"trace[1168928202] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"243.0326ms","start":"2026-01-19T16:12:56.421177Z","end":"2026-01-19T16:12:56.66421Z","steps":["trace[1168928202] 'process raft request' (duration: 242.995749ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:06.203961Z","caller":"traceutil/trace.go:171","msg":"trace[1109536180] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"111.899552ms","start":"2026-01-19T16:13:06.092036Z","end":"2026-01-19T16:13:06.203935Z","steps":["trace[1109536180] 'process raft request' (duration: 111.756438ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:10.602407Z","caller":"traceutil/trace.go:171","msg":"trace[1647273474] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"360.81807ms","start":"2026-01-19T16:13:10.241569Z","end":"2026-01-19T16:13:10.602387Z","steps":["trace[1647273474] 'process raft request' (duration: 360.247961ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:10.602542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:10.241552Z","time spent":"360.911332ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:13:11.967544Z","caller":"traceutil/trace.go:171","msg":"trace[688130863] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"127.614315ms","start":"2026-01-19T16:13:11.839905Z","end":"2026-01-19T16:13:11.967519Z","steps":["trace[688130863] 'process raft request' (duration: 127.500571ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:12.277005Z","caller":"traceutil/trace.go:171","msg":"trace[1112548754] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"196.073906ms","start":"2026-01-19T16:13:12.080906Z","end":"2026-01-19T16:13:12.27698Z","steps":["trace[1112548754] 'read index received' (duration: 149.691711ms)","trace[1112548754] 'applied index is now lower than readState.Index' (duration: 46.380745ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:13:12.277131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.199211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T16:13:12.277158Z","caller":"traceutil/trace.go:171","msg":"trace[974831949] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:906; }","duration":"196.279713ms","start":"2026-01-19T16:13:12.080869Z","end":"2026-01-19T16:13:12.277149Z","steps":["trace[974831949] 'agreement among raft nodes before linearized reading' (duration: 196.193311ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:15.001029Z","caller":"traceutil/trace.go:171","msg":"trace[307531505] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"145.811148ms","start":"2026-01-19T16:13:14.855193Z","end":"2026-01-19T16:13:15.001004Z","steps":["trace[307531505] 'process raft request' (duration: 114.999821ms)","trace[307531505] 'compare' (duration: 30.689163ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:13:19.159739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054429147,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T16:13:19.227586Z","caller":"traceutil/trace.go:171","msg":"trace[1290971280] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"568.510695ms","start":"2026-01-19T16:13:18.659048Z","end":"2026-01-19T16:13:19.227559Z","steps":["trace[1290971280] 'read index received' (duration: 568.306698ms)","trace[1290971280] 'applied index is now lower than readState.Index' (duration: 203.277µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:13:19.227701Z","caller":"traceutil/trace.go:171","msg":"trace[1500318199] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"588.126915ms","start":"2026-01-19T16:13:18.639569Z","end":"2026-01-19T16:13:19.227695Z","steps":["trace[1500318199] 'process raft request' (duration: 587.837086ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:19.227803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:18.639558Z","time spent":"588.161747ms","remote":"127.0.0.1:37702","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-01-19T16:13:19.22798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.928409ms","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-01-19T16:13:19.227997Z","caller":"traceutil/trace.go:171","msg":"trace[1801566703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"568.95405ms","start":"2026-01-19T16:13:18.659037Z","end":"2026-01-19T16:13:19.227991Z","steps":["trace[1801566703] 'agreement among raft nodes before linearized reading' (duration: 568.863907ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:19.228011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:18.658988Z","time spent":"569.020121ms","remote":"127.0.0.1:37702","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-01-19T16:13:19.228087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.695581ms","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-01-19T16:13:19.228101Z","caller":"traceutil/trace.go:171","msg":"trace[319209845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"420.744763ms","start":"2026-01-19T16:13:18.807352Z","end":"2026-01-19T16:13:19.228097Z","steps":["trace[319209845] 'agreement among raft nodes before linearized reading' (duration: 420.699681ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:19.228114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:18.807333Z","time spent":"420.778014ms","remote":"127.0.0.1:37702","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-01-19T16:13:19.228186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.178147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-19T16:13:19.228201Z","caller":"traceutil/trace.go:171","msg":"trace[437915885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"217.226528ms","start":"2026-01-19T16:13:19.010971Z","end":"2026-01-19T16:13:19.228197Z","steps":["trace[437915885] 'agreement among raft nodes before linearized reading' (duration: 217.187227ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:19.228267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.290319ms","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-01-19T16:13:19.228278Z","caller":"traceutil/trace.go:171","msg":"trace[630119708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"315.33703ms","start":"2026-01-19T16:13:18.912937Z","end":"2026-01-19T16:13:19.228274Z","steps":["trace[630119708] 'agreement among raft nodes before linearized reading' (duration: 315.298009ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:19.22829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:18.912918Z","time spent":"315.369021ms","remote":"127.0.0.1:37702","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-01-19T16:13:22.736868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.814159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T16:13:22.736969Z","caller":"traceutil/trace.go:171","msg":"trace[824646272] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:934; }","duration":"312.005095ms","start":"2026-01-19T16:13:22.424941Z","end":"2026-01-19T16:13:22.736946Z","steps":["trace[824646272] 'count revisions from in-memory index tree' (duration: 311.735696ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:22.737023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:22.424923Z","time spent":"312.087868ms","remote":"127.0.0.1:37876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:13:22.737058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.340103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:13:22.737125Z","caller":"traceutil/trace.go:171","msg":"trace[187710296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"125.457427ms","start":"2026-01-19T16:13:22.611652Z","end":"2026-01-19T16:13:22.737109Z","steps":["trace[187710296] 'range keys from in-memory index tree' (duration: 125.26214ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:23.460317Z","caller":"traceutil/trace.go:171","msg":"trace[1871808108] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"111.9795ms","start":"2026-01-19T16:13:23.348292Z","end":"2026-01-19T16:13:23.460272Z","steps":["trace[1871808108] 'process raft request' (duration: 111.66574ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:23.460501Z","caller":"traceutil/trace.go:171","msg":"trace[1446077280] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"102.929334ms","start":"2026-01-19T16:13:23.357548Z","end":"2026-01-19T16:13:23.460477Z","steps":["trace[1446077280] 'process raft request' (duration: 102.574323ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:27.902739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054429211,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T16:13:27.967633Z","caller":"traceutil/trace.go:171","msg":"trace[1154184623] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"577.314114ms","start":"2026-01-19T16:13:27.390297Z","end":"2026-01-19T16:13:27.967611Z","steps":["trace[1154184623] 'process raft request' (duration: 577.121967ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:27.967796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:27.390285Z","time spent":"577.421717ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:13:27.968117Z","caller":"traceutil/trace.go:171","msg":"trace[666577] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:986; }","duration":"566.387118ms","start":"2026-01-19T16:13:27.401723Z","end":"2026-01-19T16:13:27.96811Z","steps":["trace[666577] 'read index received' (duration: 566.383718ms)","trace[666577] 'applied index is now lower than readState.Index' (duration: 2.32µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:13:27.968221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.512962ms","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-01-19T16:13:27.96824Z","caller":"traceutil/trace.go:171","msg":"trace[1569716942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"566.532042ms","start":"2026-01-19T16:13:27.401702Z","end":"2026-01-19T16:13:27.968234Z","steps":["trace[1569716942] 'agreement among raft nodes before linearized reading' (duration: 566.45044ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:27.968254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:27.401614Z","time spent":"566.637216ms","remote":"127.0.0.1:37702","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-01-19T16:13:28.988463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.406833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:13:28.988625Z","caller":"traceutil/trace.go:171","msg":"trace[568662780] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.016590142s","start":"2026-01-19T16:13:27.972019Z","end":"2026-01-19T16:13:28.98861Z","steps":["trace[568662780] 'process raft request' (duration: 1.016546241s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:28.988672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:27.972003Z","time spent":"1.016647303s","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:13:28.988949Z","caller":"traceutil/trace.go:171","msg":"trace[2041720508] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.059593461s","start":"2026-01-19T16:13:27.929343Z","end":"2026-01-19T16:13:28.988937Z","steps":["trace[2041720508] 'process raft request' (duration: 446.640221ms)","trace[2041720508] 'compare' (duration: 612.053732ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:13:28.988995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:27.929319Z","time spent":"1.059652724s","remote":"127.0.0.1:37702","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-01-19T16:13:28.989105Z","caller":"traceutil/trace.go:171","msg":"trace[1386659120] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"1.020966561s","start":"2026-01-19T16:13:27.96813Z","end":"2026-01-19T16:13:28.989097Z","steps":["trace[1386659120] 'read index received' (duration: 407.867615ms)","trace[1386659120] 'applied index is now lower than readState.Index' (duration: 613.097925ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:13:28.989241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376594505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:13:28.989258Z","caller":"traceutil/trace.go:171","msg":"trace[60389451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"1.376654026s","start":"2026-01-19T16:13:27.612599Z","end":"2026-01-19T16:13:28.989253Z","steps":["trace[60389451] 'agreement among raft nodes before linearized reading' (duration: 1.376619835s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:28.989276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:27.612578Z","time spent":"1.376694468s","remote":"127.0.0.1:37492","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-01-19T16:13:28.989469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493572864s","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-01-19T16:13:28.989486Z","caller":"traceutil/trace.go:171","msg":"trace[582626156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"1.493619365s","start":"2026-01-19T16:13:27.495861Z","end":"2026-01-19T16:13:28.989481Z","steps":["trace[582626156] 'agreement among raft nodes before linearized reading' (duration: 1.493529962s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:28.989506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:27.495847Z","time spent":"1.493653426s","remote":"127.0.0.1:37702","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-01-19T16:13:28.989613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498042655s","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-01-19T16:13:28.98963Z","caller":"traceutil/trace.go:171","msg":"trace[670568954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"1.498072276s","start":"2026-01-19T16:13:27.491553Z","end":"2026-01-19T16:13:28.989625Z","steps":["trace[670568954] 'agreement among raft nodes before linearized reading' (duration: 1.498021255s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:28.989645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:27.491545Z","time spent":"1.498096677s","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T16:13:28.989731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500066808s","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-01-19T16:13:28.989752Z","caller":"traceutil/trace.go:171","msg":"trace[773816591] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:948; }","duration":"1.500124311s","start":"2026-01-19T16:13:27.489618Z","end":"2026-01-19T16:13:28.989742Z","steps":["trace[773816591] 'agreement among raft nodes before linearized reading' (duration: 1.50009529s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:28.989768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:27.489597Z","time spent":"1.500166542s","remote":"127.0.0.1:37766","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-01-19T16:13:28.989877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516710845s","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-01-19T16:13:28.989895Z","caller":"traceutil/trace.go:171","msg":"trace[480977636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"1.516762897s","start":"2026-01-19T16:13:27.473127Z","end":"2026-01-19T16:13:28.98989Z","steps":["trace[480977636] 'agreement among raft nodes before linearized reading' (duration: 1.516703215s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:28.989922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:27.473093Z","time spent":"1.516813798s","remote":"127.0.0.1:37702","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-01-19T16:13:28.990159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.843266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:13:28.990186Z","caller":"traceutil/trace.go:171","msg":"trace[1321085553] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:948; }","duration":"228.909718ms","start":"2026-01-19T16:13:28.761269Z","end":"2026-01-19T16:13:28.990179Z","steps":["trace[1321085553] 'agreement among raft nodes before linearized reading' (duration: 228.845456ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:28.990692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.194139ms","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-01-19T16:13:28.990713Z","caller":"traceutil/trace.go:171","msg":"trace[1274438053] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:948; }","duration":"119.25506ms","start":"2026-01-19T16:13:28.871452Z","end":"2026-01-19T16:13:28.990707Z","steps":["trace[1274438053] 'agreement among raft nodes before linearized reading' (duration: 119.219069ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:29.191661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.418138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:13:29.191817Z","caller":"traceutil/trace.go:171","msg":"trace[806711853] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:988; }","duration":"183.247704ms","start":"2026-01-19T16:13:29.008556Z","end":"2026-01-19T16:13:29.191803Z","steps":["trace[806711853] 'read index received' (duration: 80.542086ms)","trace[806711853] 'applied index is now lower than readState.Index' (duration: 102.704858ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:13:29.191885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.328316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:13:29.191898Z","caller":"traceutil/trace.go:171","msg":"trace[45411854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"183.382118ms","start":"2026-01-19T16:13:29.00851Z","end":"2026-01-19T16:13:29.191892Z","steps":["trace[45411854] 'agreement among raft nodes before linearized reading' (duration: 183.324127ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:29.192205Z","caller":"traceutil/trace.go:171","msg":"trace[1637229474] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"192.323441ms","start":"2026-01-19T16:13:28.999871Z","end":"2026-01-19T16:13:29.192194Z","steps":["trace[1637229474] 'process raft request' (duration: 89.292083ms)","trace[1637229474] 'compare' (duration: 102.281074ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:13:29.192269Z","caller":"traceutil/trace.go:171","msg":"trace[1892037124] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"192.217217ms","start":"2026-01-19T16:13:29.000047Z","end":"2026-01-19T16:13:29.192265Z","steps":["trace[1892037124] 'process raft request' (duration: 191.702231ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:29.192332Z","caller":"traceutil/trace.go:171","msg":"trace[863092335] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"185.282078ms","start":"2026-01-19T16:13:29.007045Z","end":"2026-01-19T16:13:29.192327Z","steps":["trace[863092335] 'process raft request' (duration: 184.734621ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:30.575001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003710232054429224,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T16:13:30.636682Z","caller":"traceutil/trace.go:171","msg":"trace[1072666131] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"562.67709ms","start":"2026-01-19T16:13:30.073984Z","end":"2026-01-19T16:13:30.636661Z","steps":["trace[1072666131] 'read index received' (duration: 562.448673ms)","trace[1072666131] 'applied index is now lower than readState.Index' (duration: 227.847µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:13:30.636966Z","caller":"traceutil/trace.go:171","msg":"trace[846689169] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"652.200972ms","start":"2026-01-19T16:13:29.984759Z","end":"2026-01-19T16:13:30.63696Z","steps":["trace[846689169] 'process raft request' (duration: 651.728907ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:30.637045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:29.984736Z","time spent":"652.247833ms","remote":"127.0.0.1:37702","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-01-19T16:13:30.637173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.189607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:13:30.63719Z","caller":"traceutil/trace.go:171","msg":"trace[1147793958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"563.242149ms","start":"2026-01-19T16:13:30.073943Z","end":"2026-01-19T16:13:30.637185Z","steps":["trace[1147793958] 'agreement among raft nodes before linearized reading' (duration: 563.213748ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:30.637205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:30.073923Z","time spent":"563.278969ms","remote":"127.0.0.1:37492","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-01-19T16:13:31.161054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.781802ms","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-01-19T16:13:31.161112Z","caller":"traceutil/trace.go:171","msg":"trace[1619685606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:952; }","duration":"165.888165ms","start":"2026-01-19T16:13:30.99521Z","end":"2026-01-19T16:13:31.161098Z","steps":["trace[1619685606] 'range keys from in-memory index tree' (duration: 165.605046ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:32.526119Z","caller":"traceutil/trace.go:171","msg":"trace[1971961970] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"159.851024ms","start":"2026-01-19T16:13:32.366236Z","end":"2026-01-19T16:13:32.526087Z","steps":["trace[1971961970] 'read index received' (duration: 72.717499ms)","trace[1971961970] 'applied index is now lower than readState.Index' (duration: 87.131795ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:13:32.526769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.01366ms","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":"info","ts":"2026-01-19T16:13:32.526822Z","caller":"traceutil/trace.go:171","msg":"trace[1932824279] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:956; }","duration":"160.626439ms","start":"2026-01-19T16:13:32.366183Z","end":"2026-01-19T16:13:32.526809Z","steps":["trace[1932824279] 'agreement among raft nodes before linearized reading' (duration: 160.01952ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:33.769352Z","caller":"traceutil/trace.go:171","msg":"trace[752482656] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"256.246022ms","start":"2026-01-19T16:13:33.513087Z","end":"2026-01-19T16:13:33.769333Z","steps":["trace[752482656] 'read index received' (duration: 253.936999ms)","trace[752482656] 'applied index is now lower than readState.Index' (duration: 2.307743ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:13:33.769462Z","caller":"traceutil/trace.go:171","msg":"trace[1384831606] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"283.656329ms","start":"2026-01-19T16:13:33.485755Z","end":"2026-01-19T16:13:33.769411Z","steps":["trace[1384831606] 'process raft request' (duration: 281.301624ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:33.769546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.418867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:13:33.769577Z","caller":"traceutil/trace.go:171","msg":"trace[455224369] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:965; }","duration":"256.48717ms","start":"2026-01-19T16:13:33.51308Z","end":"2026-01-19T16:13:33.769567Z","steps":["trace[455224369] 'agreement among raft nodes before linearized reading' (duration: 256.373366ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:33.769842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.475638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4783"} {"level":"info","ts":"2026-01-19T16:13:33.769856Z","caller":"traceutil/trace.go:171","msg":"trace[1365014482] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:965; }","duration":"206.535291ms","start":"2026-01-19T16:13:33.563317Z","end":"2026-01-19T16:13:33.769852Z","steps":["trace[1365014482] 'agreement among raft nodes before linearized reading' (duration: 206.448008ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:33.769975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.28767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:13:33.769988Z","caller":"traceutil/trace.go:171","msg":"trace[992908686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"155.340692ms","start":"2026-01-19T16:13:33.614643Z","end":"2026-01-19T16:13:33.769984Z","steps":["trace[992908686] 'agreement among raft nodes before linearized reading' (duration: 155.318541ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:50.925024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.698969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T16:13:50.925104Z","caller":"traceutil/trace.go:171","msg":"trace[875011972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"171.842784ms","start":"2026-01-19T16:13:50.753242Z","end":"2026-01-19T16:13:50.925085Z","steps":["trace[875011972] 'range keys from in-memory index tree' (duration: 171.521333ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:51.832947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.10179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:13:51.833057Z","caller":"traceutil/trace.go:171","msg":"trace[1132110419] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1044; }","duration":"610.265915ms","start":"2026-01-19T16:13:51.222769Z","end":"2026-01-19T16:13:51.833035Z","steps":["trace[1132110419] 'count revisions from in-memory index tree' (duration: 610.023027ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:51.833101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:51.22275Z","time spent":"610.339648ms","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:13:51.833476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.029614ms","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-01-19T16:13:51.833516Z","caller":"traceutil/trace.go:171","msg":"trace[650095806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1044; }","duration":"583.117017ms","start":"2026-01-19T16:13:51.250389Z","end":"2026-01-19T16:13:51.833506Z","steps":["trace[650095806] 'range keys from in-memory index tree' (duration: 582.808677ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:51.833543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:51.25035Z","time spent":"583.186589ms","remote":"127.0.0.1:37702","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-01-19T16:13:51.834495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.236483ms","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-01-19T16:13:51.834746Z","caller":"traceutil/trace.go:171","msg":"trace[1789990479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1044; }","duration":"535.537832ms","start":"2026-01-19T16:13:51.299149Z","end":"2026-01-19T16:13:51.834686Z","steps":["trace[1789990479] 'range keys from in-memory index tree' (duration: 534.822979ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:51.834881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.248461ms","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-01-19T16:13:51.834936Z","caller":"traceutil/trace.go:171","msg":"trace[1672380285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1044; }","duration":"330.348645ms","start":"2026-01-19T16:13:51.50457Z","end":"2026-01-19T16:13:51.834919Z","steps":["trace[1672380285] 'range keys from in-memory index tree' (duration: 330.112027ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:51.834975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:51.504548Z","time spent":"330.419597ms","remote":"127.0.0.1:37702","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-01-19T16:13:51.83556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:51.299124Z","time spent":"535.746879ms","remote":"127.0.0.1:37702","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-01-19T16:13:51.83662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.569898ms","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-01-19T16:13:51.83692Z","caller":"traceutil/trace.go:171","msg":"trace[1523621373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"476.872477ms","start":"2026-01-19T16:13:51.359991Z","end":"2026-01-19T16:13:51.836863Z","steps":["trace[1523621373] 'range keys from in-memory index tree' (duration: 476.402703ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:51.837184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:13:51.359973Z","time spent":"477.165467ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-19T16:13:55.152647Z","caller":"traceutil/trace.go:171","msg":"trace[670221128] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"110.040059ms","start":"2026-01-19T16:13:55.042582Z","end":"2026-01-19T16:13:55.152622Z","steps":["trace[670221128] 'process raft request' (duration: 109.828742ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:13:57.835074Z","caller":"traceutil/trace.go:171","msg":"trace[1612849424] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"156.809257ms","start":"2026-01-19T16:13:57.678236Z","end":"2026-01-19T16:13:57.835045Z","steps":["trace[1612849424] 'process raft request' (duration: 156.607991ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:13:58.042548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.169252ms","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-01-19T16:13:58.051393Z","caller":"traceutil/trace.go:171","msg":"trace[2072600948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"137.032753ms","start":"2026-01-19T16:13:57.914305Z","end":"2026-01-19T16:13:58.051337Z","steps":["trace[2072600948] 'range keys from in-memory index tree' (duration: 127.923585ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:14:01.987796Z","caller":"traceutil/trace.go:171","msg":"trace[1115319467] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"128.40487ms","start":"2026-01-19T16:14:01.85936Z","end":"2026-01-19T16:14:01.987765Z","steps":["trace[1115319467] 'process raft request' (duration: 128.227925ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:14:03.348432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.369965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:14:03.348952Z","caller":"traceutil/trace.go:171","msg":"trace[373865451] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"130.178026ms","start":"2026-01-19T16:14:03.218747Z","end":"2026-01-19T16:14:03.348925Z","steps":["trace[373865451] 'process raft request' (duration: 28.199952ms)","trace[373865451] 'compare' (duration: 101.246691ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:14:03.500212Z","caller":"traceutil/trace.go:171","msg":"trace[185130112] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"143.704883ms","start":"2026-01-19T16:14:03.356473Z","end":"2026-01-19T16:14:03.500178Z","steps":["trace[185130112] 'process raft request' (duration: 122.72655ms)","trace[185130112] 'compare' (duration: 20.828419ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:14:06.259762Z","caller":"traceutil/trace.go:171","msg":"trace[1129581926] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"127.7891ms","start":"2026-01-19T16:14:06.13194Z","end":"2026-01-19T16:14:06.259729Z","steps":["trace[1129581926] 'process raft request' (duration: 127.583253ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:14:08.481564Z","caller":"traceutil/trace.go:171","msg":"trace[1099344696] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"123.926078ms","start":"2026-01-19T16:14:08.357596Z","end":"2026-01-19T16:14:08.481523Z","steps":["trace[1099344696] 'process raft request' (duration: 41.516903ms)","trace[1099344696] 'compare' (duration: 81.806076ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:14:10.46734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.585436ms","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-01-19T16:14:10.467434Z","caller":"traceutil/trace.go:171","msg":"trace[1334114890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1141; }","duration":"110.738071ms","start":"2026-01-19T16:14:10.356674Z","end":"2026-01-19T16:14:10.467412Z","steps":["trace[1334114890] 'range keys from in-memory index tree' (duration: 110.360449ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:14:10.467455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.929455ms","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-01-19T16:14:10.467504Z","caller":"traceutil/trace.go:171","msg":"trace[852992994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1141; }","duration":"109.989228ms","start":"2026-01-19T16:14:10.357501Z","end":"2026-01-19T16:14:10.46749Z","steps":["trace[852992994] 'range keys from in-memory index tree' (duration: 109.832463ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:14:10.71127Z","caller":"traceutil/trace.go:171","msg":"trace[1508195496] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"208.207493ms","start":"2026-01-19T16:14:10.503035Z","end":"2026-01-19T16:14:10.711242Z","steps":["trace[1508195496] 'process raft request' (duration: 128.546004ms)","trace[1508195496] 'compare' (duration: 79.546825ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:14:10.717017Z","caller":"traceutil/trace.go:171","msg":"trace[2020016675] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"209.536115ms","start":"2026-01-19T16:14:10.507463Z","end":"2026-01-19T16:14:10.716999Z","steps":["trace[2020016675] 'process raft request' (duration: 209.305388ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:14:12.674418Z","caller":"traceutil/trace.go:171","msg":"trace[307970313] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"171.41826ms","start":"2026-01-19T16:14:12.502976Z","end":"2026-01-19T16:14:12.674394Z","steps":["trace[307970313] 'process raft request' (duration: 171.205153ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:14:12.715626Z","caller":"traceutil/trace.go:171","msg":"trace[1894447671] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"101.738867ms","start":"2026-01-19T16:14:12.613864Z","end":"2026-01-19T16:14:12.715603Z","steps":["trace[1894447671] 'read index received' (duration: 60.871155ms)","trace[1894447671] 'applied index is now lower than readState.Index' (duration: 40.866622ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:14:12.715861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.971294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:14:12.71591Z","caller":"traceutil/trace.go:171","msg":"trace[1335591950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1152; }","duration":"102.068177ms","start":"2026-01-19T16:14:12.613811Z","end":"2026-01-19T16:14:12.715879Z","steps":["trace[1335591950] 'agreement among raft nodes before linearized reading' (duration: 101.957234ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:14:12.716186Z","caller":"traceutil/trace.go:171","msg":"trace[1043262433] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"167.879268ms","start":"2026-01-19T16:14:12.548295Z","end":"2026-01-19T16:14:12.716175Z","steps":["trace[1043262433] 'process raft request' (duration: 167.141484ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:14:12.839489Z","caller":"traceutil/trace.go:171","msg":"trace[740139992] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"119.996495ms","start":"2026-01-19T16:14:12.719458Z","end":"2026-01-19T16:14:12.839454Z","steps":["trace[740139992] 'process raft request' (duration: 55.095483ms)","trace[740139992] 'compare' (duration: 64.483069ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:14:13.313743Z","caller":"traceutil/trace.go:171","msg":"trace[288864461] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"100.776687ms","start":"2026-01-19T16:14:13.212939Z","end":"2026-01-19T16:14:13.313716Z","steps":["trace[288864461] 'read index received' (duration: 100.522549ms)","trace[288864461] 'applied index is now lower than readState.Index' (duration: 252.618µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:14:13.314029Z","caller":"traceutil/trace.go:171","msg":"trace[929566447] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"136.700752ms","start":"2026-01-19T16:14:13.177297Z","end":"2026-01-19T16:14:13.313998Z","steps":["trace[929566447] 'process raft request' (duration: 136.196466ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:14:13.314042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.007904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-01-19T16:14:13.314105Z","caller":"traceutil/trace.go:171","msg":"trace[2121222882] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1154; }","duration":"101.196219ms","start":"2026-01-19T16:14:13.212893Z","end":"2026-01-19T16:14:13.314089Z","steps":["trace[2121222882] 'agreement among raft nodes before linearized reading' (duration: 100.930481ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:23.55552Z","caller":"traceutil/trace.go:171","msg":"trace[981120894] linearizableReadLoop","detail":"{readStateIndex:1503; appliedIndex:1502; }","duration":"136.592008ms","start":"2026-01-19T16:15:23.418899Z","end":"2026-01-19T16:15:23.555491Z","steps":["trace[981120894] 'read index received' (duration: 82.897683ms)","trace[981120894] 'applied index is now lower than readState.Index' (duration: 53.692925ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:15:23.555941Z","caller":"traceutil/trace.go:171","msg":"trace[1191178063] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"141.007339ms","start":"2026-01-19T16:15:23.414916Z","end":"2026-01-19T16:15:23.555923Z","steps":["trace[1191178063] 'process raft request' (duration: 86.834789ms)","trace[1191178063] 'compare' (duration: 53.557431ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:15:23.55659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.675762ms","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-01-19T16:15:23.557109Z","caller":"traceutil/trace.go:171","msg":"trace[1474883397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1439; }","duration":"138.233841ms","start":"2026-01-19T16:15:23.418844Z","end":"2026-01-19T16:15:23.557078Z","steps":["trace[1474883397] 'agreement among raft nodes before linearized reading' (duration: 137.662122ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:23.732873Z","caller":"traceutil/trace.go:171","msg":"trace[2059027313] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1504; }","duration":"119.032813ms","start":"2026-01-19T16:15:23.613813Z","end":"2026-01-19T16:15:23.732846Z","steps":["trace[2059027313] 'read index received' (duration: 5.219168ms)","trace[2059027313] 'applied index is now lower than readState.Index' (duration: 113.812545ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:15:23.733044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.191668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:15:23.733082Z","caller":"traceutil/trace.go:171","msg":"trace[2121715120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1441; }","duration":"119.276221ms","start":"2026-01-19T16:15:23.613793Z","end":"2026-01-19T16:15:23.733069Z","steps":["trace[2121715120] 'agreement among raft nodes before linearized reading' (duration: 119.152787ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:23.733464Z","caller":"traceutil/trace.go:171","msg":"trace[1577107244] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"169.369009ms","start":"2026-01-19T16:15:23.564085Z","end":"2026-01-19T16:15:23.733454Z","steps":["trace[1577107244] 'process raft request' (duration: 136.126882ms)","trace[1577107244] 'compare' (duration: 32.498353ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:15:24.17577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.936438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-19T16:15:24.175868Z","caller":"traceutil/trace.go:171","msg":"trace[769594059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1442; }","duration":"113.050781ms","start":"2026-01-19T16:15:24.062793Z","end":"2026-01-19T16:15:24.175844Z","steps":["trace[769594059] 'range keys from in-memory index tree' (duration: 112.666609ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:15:35.757841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.560159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:15:35.75791Z","caller":"traceutil/trace.go:171","msg":"trace[1684120701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1488; }","duration":"144.660593ms","start":"2026-01-19T16:15:35.613232Z","end":"2026-01-19T16:15:35.757893Z","steps":["trace[1684120701] 'range keys from in-memory index tree' (duration: 144.512908ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:15:35.75808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.941605ms","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-01-19T16:15:35.758093Z","caller":"traceutil/trace.go:171","msg":"trace[2039636138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1488; }","duration":"131.978287ms","start":"2026-01-19T16:15:35.626111Z","end":"2026-01-19T16:15:35.758089Z","steps":["trace[2039636138] 'range keys from in-memory index tree' (duration: 131.849543ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:35.866431Z","caller":"traceutil/trace.go:171","msg":"trace[1180530602] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"105.23799ms","start":"2026-01-19T16:15:35.761172Z","end":"2026-01-19T16:15:35.86641Z","steps":["trace[1180530602] 'process raft request' (duration: 101.05959ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:35.881855Z","caller":"traceutil/trace.go:171","msg":"trace[1508927680] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"119.643181ms","start":"2026-01-19T16:15:35.762193Z","end":"2026-01-19T16:15:35.881836Z","steps":["trace[1508927680] 'process raft request' (duration: 119.176076ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:38.020252Z","caller":"traceutil/trace.go:171","msg":"trace[774812266] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"105.743198ms","start":"2026-01-19T16:15:37.914483Z","end":"2026-01-19T16:15:38.020226Z","steps":["trace[774812266] 'process raft request' (duration: 41.473727ms)","trace[774812266] 'compare' (duration: 64.150447ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:15:39.186593Z","caller":"traceutil/trace.go:171","msg":"trace[1077751764] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"151.771825ms","start":"2026-01-19T16:15:39.034798Z","end":"2026-01-19T16:15:39.186569Z","steps":["trace[1077751764] 'process raft request' (duration: 143.896158ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:48.991672Z","caller":"traceutil/trace.go:171","msg":"trace[53261092] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"161.822859ms","start":"2026-01-19T16:15:48.829812Z","end":"2026-01-19T16:15:48.991635Z","steps":["trace[53261092] 'process raft request' (duration: 161.636264ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:15:50.289833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.999103ms","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-01-19T16:15:50.289932Z","caller":"traceutil/trace.go:171","msg":"trace[593211848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1547; }","duration":"129.164049ms","start":"2026-01-19T16:15:50.160741Z","end":"2026-01-19T16:15:50.289905Z","steps":["trace[593211848] 'range keys from in-memory index tree' (duration: 128.733245ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:51.298178Z","caller":"traceutil/trace.go:171","msg":"trace[851374556] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"144.107264ms","start":"2026-01-19T16:15:51.154021Z","end":"2026-01-19T16:15:51.298128Z","steps":["trace[851374556] 'process raft request' (duration: 65.044872ms)","trace[851374556] 'compare' (duration: 78.921568ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:15:51.557467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.396929ms","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-01-19T16:15:51.557536Z","caller":"traceutil/trace.go:171","msg":"trace[972958973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1552; }","duration":"116.539204ms","start":"2026-01-19T16:15:51.440981Z","end":"2026-01-19T16:15:51.55752Z","steps":["trace[972958973] 'range keys from in-memory index tree' (duration: 116.256695ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:51.750505Z","caller":"traceutil/trace.go:171","msg":"trace[1000883634] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"190.258359ms","start":"2026-01-19T16:15:51.560223Z","end":"2026-01-19T16:15:51.750482Z","steps":["trace[1000883634] 'process raft request' (duration: 190.129985ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:51.751114Z","caller":"traceutil/trace.go:171","msg":"trace[1759906639] linearizableReadLoop","detail":"{readStateIndex:1622; appliedIndex:1622; }","duration":"137.713286ms","start":"2026-01-19T16:15:51.613358Z","end":"2026-01-19T16:15:51.751072Z","steps":["trace[1759906639] 'read index received' (duration: 137.708485ms)","trace[1759906639] 'applied index is now lower than readState.Index' (duration: 3.721µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:15:51.751206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.83967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:15:51.754447Z","caller":"traceutil/trace.go:171","msg":"trace[558897401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1553; }","duration":"141.114392ms","start":"2026-01-19T16:15:51.61332Z","end":"2026-01-19T16:15:51.754435Z","steps":["trace[558897401] 'agreement among raft nodes before linearized reading' (duration: 137.84456ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:15:52.848867Z","caller":"traceutil/trace.go:171","msg":"trace[1955305441] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"251.522314ms","start":"2026-01-19T16:15:52.597281Z","end":"2026-01-19T16:15:52.848803Z","steps":["trace[1955305441] 'process raft request' (duration: 194.219783ms)","trace[1955305441] 'compare' (duration: 56.995202ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:15:59.025181Z","caller":"traceutil/trace.go:171","msg":"trace[1759778841] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"130.740228ms","start":"2026-01-19T16:15:58.894121Z","end":"2026-01-19T16:15:59.024861Z","steps":["trace[1759778841] 'process raft request' (duration: 130.592613ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:16:04.125137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.913639ms","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-01-19T16:16:04.125198Z","caller":"traceutil/trace.go:171","msg":"trace[2120862392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1601; }","duration":"112.020943ms","start":"2026-01-19T16:16:04.013163Z","end":"2026-01-19T16:16:04.125184Z","steps":["trace[2120862392] 'range keys from in-memory index tree' (duration: 111.695022ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:16:04.355635Z","caller":"traceutil/trace.go:171","msg":"trace[970031352] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"122.896302ms","start":"2026-01-19T16:16:04.232712Z","end":"2026-01-19T16:16:04.355608Z","steps":["trace[970031352] 'process raft request' (duration: 97.329663ms)","trace[970031352] 'compare' (duration: 24.867687ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:16:12.628495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.447533ms","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-01-19T16:16:12.628542Z","caller":"traceutil/trace.go:171","msg":"trace[1326540446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1637; }","duration":"118.525835ms","start":"2026-01-19T16:16:12.510005Z","end":"2026-01-19T16:16:12.62853Z","steps":["trace[1326540446] 'range keys from in-memory index tree' (duration: 118.292938ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:16:18.6455Z","caller":"traceutil/trace.go:171","msg":"trace[578379055] transaction","detail":"{read_only:false; response_revision:1662; number_of_response:1; }","duration":"163.842632ms","start":"2026-01-19T16:16:18.481631Z","end":"2026-01-19T16:16:18.645474Z","steps":["trace[578379055] 'process raft request' (duration: 71.509845ms)","trace[578379055] 'compare' (duration: 91.342906ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:17:29.645681Z","caller":"traceutil/trace.go:171","msg":"trace[356884072] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"109.470144ms","start":"2026-01-19T16:17:29.536183Z","end":"2026-01-19T16:17:29.645653Z","steps":["trace[356884072] 'process raft request' (duration: 109.312169ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:17:29.65942Z","caller":"traceutil/trace.go:171","msg":"trace[1648325526] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"123.084619ms","start":"2026-01-19T16:17:29.536317Z","end":"2026-01-19T16:17:29.659402Z","steps":["trace[1648325526] 'process raft request' (duration: 122.987676ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:17:44.820805Z","caller":"traceutil/trace.go:171","msg":"trace[989882892] linearizableReadLoop","detail":"{readStateIndex:2143; appliedIndex:2142; }","duration":"143.043702ms","start":"2026-01-19T16:17:44.677739Z","end":"2026-01-19T16:17:44.820783Z","steps":["trace[989882892] 'read index received' (duration: 142.875276ms)","trace[989882892] 'applied index is now lower than readState.Index' (duration: 167.536µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:17:44.821045Z","caller":"traceutil/trace.go:171","msg":"trace[99371385] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"151.541026ms","start":"2026-01-19T16:17:44.669456Z","end":"2026-01-19T16:17:44.820997Z","steps":["trace[99371385] 'process raft request' (duration: 151.213376ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:17:44.821339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.400388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replication-controller\" ","response":"range_response_count:1 size:763"} {"level":"warn","ts":"2026-01-19T16:17:44.821672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.909729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-01-19T16:17:44.821768Z","caller":"traceutil/trace.go:171","msg":"trace[1438816507] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2050; }","duration":"144.026992ms","start":"2026-01-19T16:17:44.677723Z","end":"2026-01-19T16:17:44.82175Z","steps":["trace[1438816507] 'agreement among raft nodes before linearized reading' (duration: 143.831736ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:17:44.821419Z","caller":"traceutil/trace.go:171","msg":"trace[418772407] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replication-controller; range_end:; response_count:1; response_revision:2050; }","duration":"105.497881ms","start":"2026-01-19T16:17:44.715902Z","end":"2026-01-19T16:17:44.8214Z","steps":["trace[418772407] 'agreement among raft nodes before linearized reading' (duration: 105.357387ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:17:48.674826Z","caller":"traceutil/trace.go:171","msg":"trace[1242236216] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"130.545362ms","start":"2026-01-19T16:17:48.544264Z","end":"2026-01-19T16:17:48.674809Z","steps":["trace[1242236216] 'process raft request' (duration: 129.942273ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:17:55.452717Z","caller":"traceutil/trace.go:171","msg":"trace[2010996158] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"131.540432ms","start":"2026-01-19T16:17:55.321162Z","end":"2026-01-19T16:17:55.452702Z","steps":["trace[2010996158] 'process raft request' (duration: 67.97695ms)","trace[2010996158] 'compare' (duration: 63.127359ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:17:56.892982Z","caller":"traceutil/trace.go:171","msg":"trace[1250866891] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2195; }","duration":"158.874755ms","start":"2026-01-19T16:17:56.73409Z","end":"2026-01-19T16:17:56.892964Z","steps":["trace[1250866891] 'read index received' (duration: 65.848574ms)","trace[1250866891] 'applied index is now lower than readState.Index' (duration: 93.025511ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:17:56.893147Z","caller":"traceutil/trace.go:171","msg":"trace[1644273957] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"167.846944ms","start":"2026-01-19T16:17:56.725291Z","end":"2026-01-19T16:17:56.893138Z","steps":["trace[1644273957] 'process raft request' (duration: 74.688169ms)","trace[1644273957] 'compare' (duration: 92.719701ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:17:56.893292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.195314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4598"} {"level":"info","ts":"2026-01-19T16:17:56.893333Z","caller":"traceutil/trace.go:171","msg":"trace[1275034495] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:2101; }","duration":"159.279797ms","start":"2026-01-19T16:17:56.734026Z","end":"2026-01-19T16:17:56.893306Z","steps":["trace[1275034495] 'agreement among raft nodes before linearized reading' (duration: 159.226876ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:17:56.893473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.66117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:951"} {"level":"info","ts":"2026-01-19T16:17:56.89349Z","caller":"traceutil/trace.go:171","msg":"trace[2008885841] range","detail":"{range_begin:/registry/rolebindings/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2101; }","duration":"118.700612ms","start":"2026-01-19T16:17:56.774784Z","end":"2026-01-19T16:17:56.893484Z","steps":["trace[2008885841] 'agreement among raft nodes before linearized reading' (duration: 118.64027ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:17:56.893638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.518603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-19T16:17:56.893654Z","caller":"traceutil/trace.go:171","msg":"trace[1134289741] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:2101; }","duration":"107.558554ms","start":"2026-01-19T16:17:56.786091Z","end":"2026-01-19T16:17:56.893649Z","steps":["trace[1134289741] 'agreement among raft nodes before linearized reading' (duration: 107.512002ms)"],"step_count":1}