{"level":"warn","ts":"2026-08-20T20:29:17.883486Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T20:29:17.883598Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.233: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.233:2380","--initial-cluster=instance=https://199.19.213.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.233: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-08-20T20:29:17.883682Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T20:29:17.883703Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-08-20T20:29:17.883734Z","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-08-20T20:29:17.884624Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"]} {"level":"info","ts":"2026-08-20T20:29:17.884731Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.233: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-08-20T20:29:17.889616Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.526971ms"} {"level":"info","ts":"2026-08-20T20:29:17.903063Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de5c2ed1f829ccc5","cluster-id":"1c2360c7fdb72bdb"} {"level":"info","ts":"2026-08-20T20:29:17.903159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T20:29:17.903288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 0"} {"level":"info","ts":"2026-08-20T20:29:17.903308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de5c2ed1f829ccc5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T20:29:17.903317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 1"} {"level":"info","ts":"2026-08-20T20:29:17.90359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"warn","ts":"2026-08-20T20:29:17.909414Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T20:29:17.910894Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T20:29:17.912269Z","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-08-20T20:29:17.914855Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de5c2ed1f829ccc5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T20:29:17.91541Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de5c2ed1f829ccc5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T20:29:17.915485Z","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-08-20T20:29:17.915556Z","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-08-20T20:29:17.915567Z","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-08-20T20:29:17.917887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"info","ts":"2026-08-20T20:29:17.918186Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","added-peer-id":"de5c2ed1f829ccc5","added-peer-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-08-20T20:29:17.923232Z","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-08-20T20:29:17.923327Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-08-20T20:29:17.92337Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-08-20T20:29:17.923656Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de5c2ed1f829ccc5","initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T20:29:17.923736Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T20:29:18.004029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T20:29:18.004117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T20:29:18.004158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgPreVoteResp from de5c2ed1f829ccc5 at term 1"} {"level":"info","ts":"2026-08-20T20:29:18.004181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became candidate at term 2"} {"level":"info","ts":"2026-08-20T20:29:18.004196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgVoteResp from de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-08-20T20:29:18.004222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became leader at term 2"} {"level":"info","ts":"2026-08-20T20:29:18.004239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de5c2ed1f829ccc5 elected leader de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-08-20T20:29:18.00836Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:29:18.015642Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de5c2ed1f829ccc5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.233:2379]}","request-path":"/0/members/de5c2ed1f829ccc5/attributes","cluster-id":"1c2360c7fdb72bdb","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T20:29:18.016035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T20:29:18.016058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T20:29:18.016133Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T20:29:18.016205Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T20:29:18.0174Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:29:18.017512Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:29:18.017566Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:29:18.019525Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.233:2379"} {"level":"info","ts":"2026-08-20T20:29:18.026854Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-20T20:29:32.044201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158611519,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T20:29:32.269939Z","caller":"traceutil/trace.go:171","msg":"trace[951997654] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:370; }","duration":"726.333017ms","start":"2026-08-20T20:29:31.543576Z","end":"2026-08-20T20:29:32.269909Z","steps":["trace[951997654] 'read index received' (duration: 726.107208ms)","trace[951997654] 'applied index is now lower than readState.Index' (duration: 224.699µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:29:32.270403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.315563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-20T20:29:32.270343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.706333ms","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-08-20T20:29:32.270504Z","caller":"traceutil/trace.go:171","msg":"trace[1053715099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:360; }","duration":"726.938632ms","start":"2026-08-20T20:29:31.543534Z","end":"2026-08-20T20:29:32.270473Z","steps":["trace[1053715099] 'agreement among raft nodes before linearized reading' (duration: 726.63636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:32.270593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:31.543515Z","time spent":"727.062287ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-20T20:29:32.270781Z","caller":"traceutil/trace.go:171","msg":"trace[460329767] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"817.76507ms","start":"2026-08-20T20:29:31.453002Z","end":"2026-08-20T20:29:32.270767Z","steps":["trace[460329767] 'process raft request' (duration: 816.765549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:32.270818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.847963ms","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-08-20T20:29:32.270858Z","caller":"traceutil/trace.go:171","msg":"trace[1685382619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:360; }","duration":"201.925516ms","start":"2026-08-20T20:29:32.068923Z","end":"2026-08-20T20:29:32.270848Z","steps":["trace[1685382619] 'agreement among raft nodes before linearized reading' (duration: 201.864613ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:29:32.270446Z","caller":"traceutil/trace.go:171","msg":"trace[362345093] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:360; }","duration":"204.421697ms","start":"2026-08-20T20:29:32.066012Z","end":"2026-08-20T20:29:32.270434Z","steps":["trace[362345093] 'agreement among raft nodes before linearized reading' (duration: 204.319023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:32.271383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:31.452976Z","time spent":"817.830042ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:29:37.632308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.079754ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T20:29:37.632453Z","caller":"traceutil/trace.go:171","msg":"trace[2119888769] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"199.569824ms","start":"2026-08-20T20:29:37.43286Z","end":"2026-08-20T20:29:37.63243Z","steps":["trace[2119888769] 'read index received' (duration: 96.164717ms)","trace[2119888769] 'applied index is now lower than readState.Index' (duration: 103.403347ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:29:37.632544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.680949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:29:37.632587Z","caller":"traceutil/trace.go:171","msg":"trace[481552322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"199.772272ms","start":"2026-08-20T20:29:37.432802Z","end":"2026-08-20T20:29:37.632575Z","steps":["trace[481552322] 'agreement among raft nodes before linearized reading' (duration: 199.685989ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:29:38.948842Z","caller":"traceutil/trace.go:171","msg":"trace[1276860882] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"641.391918ms","start":"2026-08-20T20:29:38.307413Z","end":"2026-08-20T20:29:38.948805Z","steps":["trace[1276860882] 'process raft request' (duration: 641.20736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:38.949027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:38.307401Z","time spent":"641.542875ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:29:39.031289Z","caller":"traceutil/trace.go:171","msg":"trace[1735434360] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"722.554622ms","start":"2026-08-20T20:29:38.308704Z","end":"2026-08-20T20:29:39.031259Z","steps":["trace[1735434360] 'process raft request' (duration: 722.379625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:39.031426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:38.308696Z","time spent":"722.666827ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:29:39.031406Z","caller":"traceutil/trace.go:171","msg":"trace[1721119438] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"721.078192ms","start":"2026-08-20T20:29:38.310248Z","end":"2026-08-20T20:29:39.031326Z","steps":["trace[1721119438] 'process raft request' (duration: 720.953847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:39.031556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:38.31024Z","time spent":"721.240568ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:29:39.381489Z","caller":"traceutil/trace.go:171","msg":"trace[578024722] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"346.478718ms","start":"2026-08-20T20:29:39.034973Z","end":"2026-08-20T20:29:39.381452Z","steps":["trace[578024722] 'process raft request' (duration: 303.810899ms)","trace[578024722] 'compare' (duration: 42.45434ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:29:39.381689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:39.034947Z","time spent":"346.644735ms","remote":"127.0.0.1:54592","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-08-20T20:29:45.551261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158611606,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T20:29:45.882688Z","caller":"traceutil/trace.go:171","msg":"trace[776326307] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"831.700903ms","start":"2026-08-20T20:29:45.050958Z","end":"2026-08-20T20:29:45.882659Z","steps":["trace[776326307] 'read index received' (duration: 831.438862ms)","trace[776326307] 'applied index is now lower than readState.Index' (duration: 260.471µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:29:45.882843Z","caller":"traceutil/trace.go:171","msg":"trace[305055896] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"903.074787ms","start":"2026-08-20T20:29:44.979718Z","end":"2026-08-20T20:29:45.882792Z","steps":["trace[305055896] 'process raft request' (duration: 902.748103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:45.883063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.27601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-08-20T20:29:45.883126Z","caller":"traceutil/trace.go:171","msg":"trace[1369754129] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:394; }","duration":"255.367614ms","start":"2026-08-20T20:29:45.627733Z","end":"2026-08-20T20:29:45.883101Z","steps":["trace[1369754129] 'agreement among raft nodes before linearized reading' (duration: 255.28323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:45.882925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.938154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-20T20:29:45.88322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:44.979692Z","time spent":"903.405531ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:29:45.883263Z","caller":"traceutil/trace.go:171","msg":"trace[444460838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:394; }","duration":"832.338412ms","start":"2026-08-20T20:29:45.050899Z","end":"2026-08-20T20:29:45.883237Z","steps":["trace[444460838] 'agreement among raft nodes before linearized reading' (duration: 831.890402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:45.883349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:45.050875Z","time spent":"832.433056ms","remote":"127.0.0.1:54592","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-08-20T20:29:45.882993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.056754ms","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-08-20T20:29:45.883483Z","caller":"traceutil/trace.go:171","msg":"trace[1210234130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:394; }","duration":"830.634169ms","start":"2026-08-20T20:29:45.052836Z","end":"2026-08-20T20:29:45.88347Z","steps":["trace[1210234130] 'agreement among raft nodes before linearized reading' (duration: 830.012551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:45.883523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:45.052815Z","time spent":"830.698181ms","remote":"127.0.0.1:54592","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-08-20T20:29:45.883049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.76187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T20:29:45.883628Z","caller":"traceutil/trace.go:171","msg":"trace[562386812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:394; }","duration":"480.336156ms","start":"2026-08-20T20:29:45.403278Z","end":"2026-08-20T20:29:45.883614Z","steps":["trace[562386812] 'agreement among raft nodes before linearized reading' (duration: 479.74455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:45.883674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:45.403188Z","time spent":"480.471262ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T20:29:47.982003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.689146ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T20:29:48.348886Z","caller":"traceutil/trace.go:171","msg":"trace[1766448652] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"362.294879ms","start":"2026-08-20T20:29:47.986557Z","end":"2026-08-20T20:29:48.348852Z","steps":["trace[1766448652] 'process raft request' (duration: 362.07434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:48.349108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:47.986541Z","time spent":"362.448626ms","remote":"127.0.0.1:54592","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-08-20T20:29:49.726478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.796802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:29:49.726721Z","caller":"traceutil/trace.go:171","msg":"trace[354002759] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:443; }","duration":"1.293918249s","start":"2026-08-20T20:29:48.432787Z","end":"2026-08-20T20:29:49.726706Z","steps":["trace[354002759] 'read index received' (duration: 359.765222ms)","trace[354002759] 'applied index is now lower than readState.Index' (duration: 934.152067ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:29:49.726802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294019674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:29:49.72683Z","caller":"traceutil/trace.go:171","msg":"trace[1285584115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"1.294084797s","start":"2026-08-20T20:29:48.432735Z","end":"2026-08-20T20:29:49.72682Z","steps":["trace[1285584115] 'agreement among raft nodes before linearized reading' (duration: 1.294018284s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:49.726861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:48.432715Z","time spent":"1.29413918s","remote":"127.0.0.1:54332","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-08-20T20:29:49.726904Z","caller":"traceutil/trace.go:171","msg":"trace[213765398] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"1.738726623s","start":"2026-08-20T20:29:47.988157Z","end":"2026-08-20T20:29:49.726883Z","steps":["trace[213765398] 'process raft request' (duration: 1.738510833s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:29:49.72692Z","caller":"traceutil/trace.go:171","msg":"trace[1076087496] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"1.73981395s","start":"2026-08-20T20:29:47.987062Z","end":"2026-08-20T20:29:49.726876Z","steps":["trace[1076087496] 'process raft request' (duration: 1.739543327s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:29:49.726944Z","caller":"traceutil/trace.go:171","msg":"trace[466690740] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"1.739956956s","start":"2026-08-20T20:29:47.986939Z","end":"2026-08-20T20:29:49.726896Z","steps":["trace[466690740] 'process raft request' (duration: 805.657212ms)","trace[466690740] 'compare' (duration: 933.544431ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:29:49.72701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:47.988127Z","time spent":"1.738820257s","remote":"127.0.0.1:54592","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-08-20T20:29:49.727085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:47.987055Z","time spent":"1.739953335s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:29:49.727139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:47.986924Z","time spent":"1.740096342s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:29:51.684663Z","caller":"traceutil/trace.go:171","msg":"trace[5708767] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"251.930678ms","start":"2026-08-20T20:29:51.432709Z","end":"2026-08-20T20:29:51.68464Z","steps":["trace[5708767] 'read index received' (duration: 251.73344ms)","trace[5708767] 'applied index is now lower than readState.Index' (duration: 196.398µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:29:51.684699Z","caller":"traceutil/trace.go:171","msg":"trace[1413424478] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"650.889699ms","start":"2026-08-20T20:29:51.033782Z","end":"2026-08-20T20:29:51.684671Z","steps":["trace[1413424478] 'process raft request' (duration: 650.707581ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:51.684779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.048523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:29:51.684812Z","caller":"traceutil/trace.go:171","msg":"trace[1286903588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"252.125596ms","start":"2026-08-20T20:29:51.432677Z","end":"2026-08-20T20:29:51.684803Z","steps":["trace[1286903588] 'agreement among raft nodes before linearized reading' (duration: 252.055393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:51.684818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:51.033762Z","time spent":"650.991423ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:29:52.567991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158611669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:29:52.885137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.857423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:29:52.885216Z","caller":"traceutil/trace.go:171","msg":"trace[1021646440] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"1.148389621s","start":"2026-08-20T20:29:51.736814Z","end":"2026-08-20T20:29:52.885204Z","steps":["trace[1021646440] 'process raft request' (duration: 220.391442ms)","trace[1021646440] 'compare' (duration: 927.681565ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:29:52.885256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:51.7368Z","time spent":"1.148433613s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:29:52.888368Z","caller":"traceutil/trace.go:171","msg":"trace[1513330409] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:449; }","duration":"821.205473ms","start":"2026-08-20T20:29:52.067147Z","end":"2026-08-20T20:29:52.888353Z","steps":["trace[1513330409] 'read index received' (duration: 821.026046ms)","trace[1513330409] 'applied index is now lower than readState.Index' (duration: 178.807µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:29:52.888566Z","caller":"traceutil/trace.go:171","msg":"trace[1521459381] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"1.151522987s","start":"2026-08-20T20:29:51.737035Z","end":"2026-08-20T20:29:52.888558Z","steps":["trace[1521459381] 'process raft request' (duration: 1.151157191s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:52.888647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:51.737024Z","time spent":"1.151565169s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:29:52.888755Z","caller":"traceutil/trace.go:171","msg":"trace[682082604] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"1.151618211s","start":"2026-08-20T20:29:51.73713Z","end":"2026-08-20T20:29:52.888749Z","steps":["trace[682082604] 'process raft request' (duration: 1.151193773s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:52.888789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:51.737109Z","time spent":"1.151662382s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:29:52.888871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.725995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T20:29:52.888896Z","caller":"traceutil/trace.go:171","msg":"trace[1455408043] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:435; }","duration":"821.775108ms","start":"2026-08-20T20:29:52.067114Z","end":"2026-08-20T20:29:52.888889Z","steps":["trace[1455408043] 'agreement among raft nodes before linearized reading' (duration: 821.731876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:52.888913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:52.067102Z","time spent":"821.807269ms","remote":"127.0.0.1:54342","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.233\" "} {"level":"warn","ts":"2026-08-20T20:29:52.889008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.953702ms","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-08-20T20:29:52.889025Z","caller":"traceutil/trace.go:171","msg":"trace[1524825107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:435; }","duration":"497.994744ms","start":"2026-08-20T20:29:52.391026Z","end":"2026-08-20T20:29:52.889021Z","steps":["trace[1524825107] 'agreement among raft nodes before linearized reading' (duration: 497.964813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:52.889058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:52.391013Z","time spent":"498.023435ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T20:29:52.889186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.197997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:29:52.889205Z","caller":"traceutil/trace.go:171","msg":"trace[1933572454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:435; }","duration":"456.217399ms","start":"2026-08-20T20:29:52.432983Z","end":"2026-08-20T20:29:52.8892Z","steps":["trace[1933572454] 'agreement among raft nodes before linearized reading' (duration: 456.185308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:52.88922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:29:52.432927Z","time spent":"456.289322ms","remote":"127.0.0.1:54332","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-08-20T20:29:53.064715Z","caller":"traceutil/trace.go:171","msg":"trace[406671319] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"163.335106ms","start":"2026-08-20T20:29:52.901354Z","end":"2026-08-20T20:29:53.064689Z","steps":["trace[406671319] 'process raft request' (duration: 163.272304ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:29:53.065029Z","caller":"traceutil/trace.go:171","msg":"trace[1277196325] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"172.508155ms","start":"2026-08-20T20:29:52.892483Z","end":"2026-08-20T20:29:53.064992Z","steps":["trace[1277196325] 'process raft request' (duration: 122.37972ms)","trace[1277196325] 'compare' (duration: 49.620513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:29:54.553138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.09225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:29:54.553217Z","caller":"traceutil/trace.go:171","msg":"trace[143286189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:438; }","duration":"123.217766ms","start":"2026-08-20T20:29:54.429981Z","end":"2026-08-20T20:29:54.553199Z","steps":["trace[143286189] 'range keys from in-memory index tree' (duration: 123.011367ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:29:55.037741Z","caller":"traceutil/trace.go:171","msg":"trace[718642804] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"131.69172ms","start":"2026-08-20T20:29:54.906023Z","end":"2026-08-20T20:29:55.037715Z","steps":["trace[718642804] 'process raft request' (duration: 130.054568ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:29:55.038491Z","caller":"traceutil/trace.go:171","msg":"trace[2096615144] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"131.517972ms","start":"2026-08-20T20:29:54.906949Z","end":"2026-08-20T20:29:55.038467Z","steps":["trace[2096615144] 'process raft request' (duration: 131.392337ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:29:57.320795Z","caller":"traceutil/trace.go:171","msg":"trace[1520413141] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"138.584196ms","start":"2026-08-20T20:29:57.182192Z","end":"2026-08-20T20:29:57.320777Z","steps":["trace[1520413141] 'process raft request' (duration: 138.45537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:59.186116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.58621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-20T20:29:59.186172Z","caller":"traceutil/trace.go:171","msg":"trace[743161030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:447; }","duration":"136.697855ms","start":"2026-08-20T20:29:59.049463Z","end":"2026-08-20T20:29:59.186161Z","steps":["trace[743161030] 'range keys from in-memory index tree' (duration: 136.442593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:29:59.186146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.149296ms","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-08-20T20:29:59.186327Z","caller":"traceutil/trace.go:171","msg":"trace[328579219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:447; }","duration":"135.381145ms","start":"2026-08-20T20:29:59.050931Z","end":"2026-08-20T20:29:59.186312Z","steps":["trace[328579219] 'range keys from in-memory index tree' (duration: 135.054092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:01.740615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.85022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:30:01.740933Z","caller":"traceutil/trace.go:171","msg":"trace[919860589] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"152.519447ms","start":"2026-08-20T20:30:01.58839Z","end":"2026-08-20T20:30:01.740909Z","steps":["trace[919860589] 'process raft request' (duration: 152.412793ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:01.740925Z","caller":"traceutil/trace.go:171","msg":"trace[1912652307] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"158.083776ms","start":"2026-08-20T20:30:01.582804Z","end":"2026-08-20T20:30:01.740888Z","steps":["trace[1912652307] 'compare' (duration: 149.614379ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:03.368458Z","caller":"traceutil/trace.go:171","msg":"trace[1487306314] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"142.030312ms","start":"2026-08-20T20:30:03.226366Z","end":"2026-08-20T20:30:03.368396Z","steps":["trace[1487306314] 'read index received' (duration: 141.769981ms)","trace[1487306314] 'applied index is now lower than readState.Index' (duration: 259.421µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:03.368478Z","caller":"traceutil/trace.go:171","msg":"trace[904640157] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"207.850055ms","start":"2026-08-20T20:30:03.160576Z","end":"2026-08-20T20:30:03.368426Z","steps":["trace[904640157] 'process raft request' (duration: 207.596843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:03.368745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.358127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-20T20:30:03.368804Z","caller":"traceutil/trace.go:171","msg":"trace[1860248775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"142.466232ms","start":"2026-08-20T20:30:03.226322Z","end":"2026-08-20T20:30:03.368788Z","steps":["trace[1860248775] 'agreement among raft nodes before linearized reading' (duration: 142.226271ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:03.376985Z","caller":"traceutil/trace.go:171","msg":"trace[1239162610] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"115.715834ms","start":"2026-08-20T20:30:03.261243Z","end":"2026-08-20T20:30:03.376958Z","steps":["trace[1239162610] 'process raft request' (duration: 115.453952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:03.377038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.594745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-20T20:30:03.377072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.051687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-08-20T20:30:03.377115Z","caller":"traceutil/trace.go:171","msg":"trace[194339015] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:589; }","duration":"126.13471ms","start":"2026-08-20T20:30:03.250968Z","end":"2026-08-20T20:30:03.377102Z","steps":["trace[194339015] 'agreement among raft nodes before linearized reading' (duration: 126.002875ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:03.377088Z","caller":"traceutil/trace.go:171","msg":"trace[818887762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"150.669978ms","start":"2026-08-20T20:30:03.226406Z","end":"2026-08-20T20:30:03.377076Z","steps":["trace[818887762] 'agreement among raft nodes before linearized reading' (duration: 150.524642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:04.358932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158611944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:30:04.860077Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158611944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:30:05.204763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497814598s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:30:05.204863Z","caller":"traceutil/trace.go:171","msg":"trace[516022798] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.664997585s","start":"2026-08-20T20:30:03.539852Z","end":"2026-08-20T20:30:05.20485Z","steps":["trace[516022798] 'process raft request' (duration: 167.03226ms)","trace[516022798] 'compare' (duration: 1.497502623s)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:05.204905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:03.539832Z","time spent":"1.665053057s","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:05.360496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158611944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:30:05.860801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158611944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:30:06.198571Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.667748296s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-20T20:30:06.303793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.963007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:30:06.303888Z","caller":"traceutil/trace.go:171","msg":"trace[1049031273] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.643648097s","start":"2026-08-20T20:30:03.660225Z","end":"2026-08-20T20:30:06.303873Z","steps":["trace[1049031273] 'process raft request' (duration: 2.538527874s)","trace[1049031273] 'compare' (duration: 104.335128ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:06.303947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:03.660198Z","time spent":"2.6437126s","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:30:06.333961Z","caller":"traceutil/trace.go:171","msg":"trace[862615591] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:619; }","duration":"2.475999418s","start":"2026-08-20T20:30:03.857944Z","end":"2026-08-20T20:30:06.333944Z","steps":["trace[862615591] 'read index received' (duration: 2.341088233s)","trace[862615591] 'applied index is now lower than readState.Index' (duration: 134.910575ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:06.334055Z","caller":"traceutil/trace.go:171","msg":"trace[1359517344] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.114685772s","start":"2026-08-20T20:30:05.21936Z","end":"2026-08-20T20:30:06.334046Z","steps":["trace[1359517344] 'process raft request' (duration: 1.114543226s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:06.334046Z","caller":"traceutil/trace.go:171","msg":"trace[985859762] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.86863778s","start":"2026-08-20T20:30:04.465376Z","end":"2026-08-20T20:30:06.334013Z","steps":["trace[985859762] 'process raft request' (duration: 1.868477832s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:06.334076Z","caller":"traceutil/trace.go:171","msg":"trace[993315200] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.875837462s","start":"2026-08-20T20:30:04.458207Z","end":"2026-08-20T20:30:06.334044Z","steps":["trace[993315200] 'process raft request' (duration: 1.875553359s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.334139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:05.219332Z","time spent":"1.114744905s","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:06.334213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:04.465361Z","time spent":"1.868755454s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:06.334244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:04.458175Z","time spent":"1.875983919s","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T20:30:06.334285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.476333274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:06.33446Z","caller":"traceutil/trace.go:171","msg":"trace[1363663836] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"2.476548213s","start":"2026-08-20T20:30:03.857887Z","end":"2026-08-20T20:30:06.334435Z","steps":["trace[1363663836] 'agreement among raft nodes before linearized reading' (duration: 2.476360525s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.334571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:03.857864Z","time spent":"2.476682779s","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-08-20T20:30:06.353086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.295745537s","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-08-20T20:30:06.353155Z","caller":"traceutil/trace.go:171","msg":"trace[1387560940] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:602; }","duration":"2.295866543s","start":"2026-08-20T20:30:04.057273Z","end":"2026-08-20T20:30:06.353139Z","steps":["trace[1387560940] 'agreement among raft nodes before linearized reading' (duration: 2.295722327s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.353162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096312739s","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-08-20T20:30:06.353229Z","caller":"traceutil/trace.go:171","msg":"trace[1274263518] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:602; }","duration":"1.096390492s","start":"2026-08-20T20:30:05.25682Z","end":"2026-08-20T20:30:06.35321Z","steps":["trace[1274263518] 'agreement among raft nodes before linearized reading' (duration: 1.096217054s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.353279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:05.256771Z","time spent":"1.096498638s","remote":"127.0.0.1:54360","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-08-20T20:30:06.353298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.345668ms","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-08-20T20:30:06.353354Z","caller":"traceutil/trace.go:171","msg":"trace[1879453598] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:602; }","duration":"590.434142ms","start":"2026-08-20T20:30:05.762901Z","end":"2026-08-20T20:30:06.353336Z","steps":["trace[1879453598] 'agreement among raft nodes before linearized reading' (duration: 590.356288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.353391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:05.76288Z","time spent":"590.503635ms","remote":"127.0.0.1:54360","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-08-20T20:30:06.353224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.261491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-08-20T20:30:06.35352Z","caller":"traceutil/trace.go:171","msg":"trace[1770523140] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:602; }","duration":"896.595857ms","start":"2026-08-20T20:30:05.456915Z","end":"2026-08-20T20:30:06.353511Z","steps":["trace[1770523140] 'agreement among raft nodes before linearized reading' (duration: 896.158996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.3535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.335549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-20T20:30:06.353584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:05.456899Z","time spent":"896.67612ms","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-08-20T20:30:06.353623Z","caller":"traceutil/trace.go:171","msg":"trace[2029867363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"915.487496ms","start":"2026-08-20T20:30:05.438113Z","end":"2026-08-20T20:30:06.353601Z","steps":["trace[2029867363] 'agreement among raft nodes before linearized reading' (duration: 915.225164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.353892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.62092104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:30:06.353948Z","caller":"traceutil/trace.go:171","msg":"trace[569710701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:602; }","duration":"1.621003914s","start":"2026-08-20T20:30:04.732915Z","end":"2026-08-20T20:30:06.353919Z","steps":["trace[569710701] 'agreement among raft nodes before linearized reading' (duration: 1.620517052s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.353906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:05.438095Z","time spent":"915.795379ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T20:30:06.353978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:04.73289Z","time spent":"1.621080917s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-20T20:30:06.354034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.874338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-20T20:30:06.35408Z","caller":"traceutil/trace.go:171","msg":"trace[1228802511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"971.952842ms","start":"2026-08-20T20:30:05.382117Z","end":"2026-08-20T20:30:06.354069Z","steps":["trace[1228802511] 'agreement among raft nodes before linearized reading' (duration: 971.743622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.35411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:05.382101Z","time spent":"972.001904ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-20T20:30:06.354131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29709918s","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-08-20T20:30:06.354209Z","caller":"traceutil/trace.go:171","msg":"trace[1942594436] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:602; }","duration":"1.297179433s","start":"2026-08-20T20:30:05.056984Z","end":"2026-08-20T20:30:06.354163Z","steps":["trace[1942594436] 'agreement among raft nodes before linearized reading' (duration: 1.297075908s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.354239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:05.056966Z","time spent":"1.297266487s","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-08-20T20:30:06.354246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402154704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T20:30:06.354272Z","caller":"traceutil/trace.go:171","msg":"trace[1500978966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"1.402214047s","start":"2026-08-20T20:30:04.95205Z","end":"2026-08-20T20:30:06.354264Z","steps":["trace[1500978966] 'agreement among raft nodes before linearized reading' (duration: 1.402128664s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.354304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:04.952033Z","time spent":"1.402265829s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T20:30:06.354414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497264598s","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":"warn","ts":"2026-08-20T20:30:06.354429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.922204904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:06.35447Z","caller":"traceutil/trace.go:171","msg":"trace[608307435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"1.922288858s","start":"2026-08-20T20:30:04.432169Z","end":"2026-08-20T20:30:06.354458Z","steps":["trace[608307435] 'agreement among raft nodes before linearized reading' (duration: 1.922228855s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.3545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:04.432135Z","time spent":"1.922358152s","remote":"127.0.0.1:54332","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-08-20T20:30:06.353185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:04.057253Z","time spent":"2.295926375s","remote":"127.0.0.1:54360","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-08-20T20:30:06.354453Z","caller":"traceutil/trace.go:171","msg":"trace[1842247074] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:602; }","duration":"1.497298229s","start":"2026-08-20T20:30:04.857142Z","end":"2026-08-20T20:30:06.35444Z","steps":["trace[1842247074] 'agreement among raft nodes before linearized reading' (duration: 1.497177454s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.354677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697645729s","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":"warn","ts":"2026-08-20T20:30:06.354704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.097313025s","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":"warn","ts":"2026-08-20T20:30:06.354692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:04.857082Z","time spent":"1.497599663s","remote":"127.0.0.1:54360","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":"info","ts":"2026-08-20T20:30:06.354714Z","caller":"traceutil/trace.go:171","msg":"trace[253972050] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:602; }","duration":"1.697739293s","start":"2026-08-20T20:30:04.656965Z","end":"2026-08-20T20:30:06.354704Z","steps":["trace[253972050] 'agreement among raft nodes before linearized reading' (duration: 1.697611497s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:06.35474Z","caller":"traceutil/trace.go:171","msg":"trace[752453948] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:602; }","duration":"2.097345636s","start":"2026-08-20T20:30:04.257381Z","end":"2026-08-20T20:30:06.354727Z","steps":["trace[752453948] 'agreement among raft nodes before linearized reading' (duration: 2.097297244s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.354768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:04.257278Z","time spent":"2.0974451s","remote":"127.0.0.1:54360","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-08-20T20:30:06.354806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.303198ms","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-08-20T20:30:06.354912Z","caller":"traceutil/trace.go:171","msg":"trace[422741245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"962.591761ms","start":"2026-08-20T20:30:05.392302Z","end":"2026-08-20T20:30:06.354894Z","steps":["trace[422741245] 'agreement among raft nodes before linearized reading' (duration: 961.004611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:06.354954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:04.65695Z","time spent":"1.697987784s","remote":"127.0.0.1:54360","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-08-20T20:30:06.355014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:05.39228Z","time spent":"962.715077ms","remote":"127.0.0.1:54592","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-08-20T20:30:09.375779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.573832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:30:09.375887Z","caller":"traceutil/trace.go:171","msg":"trace[994220903] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"271.756415ms","start":"2026-08-20T20:30:09.104111Z","end":"2026-08-20T20:30:09.375868Z","steps":["trace[994220903] 'process raft request' (duration: 93.032017ms)","trace[994220903] 'compare' (duration: 178.416195ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:12.14504Z","caller":"traceutil/trace.go:171","msg":"trace[1367501097] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"173.719919ms","start":"2026-08-20T20:30:11.971294Z","end":"2026-08-20T20:30:12.145014Z","steps":["trace[1367501097] 'process raft request' (duration: 173.559801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:12.789871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.829765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:30:12.789948Z","caller":"traceutil/trace.go:171","msg":"trace[1447153899] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"370.601249ms","start":"2026-08-20T20:30:12.419335Z","end":"2026-08-20T20:30:12.789936Z","steps":["trace[1447153899] 'read index received' (duration: 223.553954ms)","trace[1447153899] 'applied index is now lower than readState.Index' (duration: 147.046495ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:12.790007Z","caller":"traceutil/trace.go:171","msg":"trace[2138989224] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"387.445397ms","start":"2026-08-20T20:30:12.402555Z","end":"2026-08-20T20:30:12.79Z","steps":["trace[2138989224] 'process raft request' (duration: 240.426573ms)","trace[2138989224] 'compare' (duration: 146.530371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:12.790062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:12.402483Z","time spent":"387.558841ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:12.790143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.96104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:30:12.790225Z","caller":"traceutil/trace.go:171","msg":"trace[82786908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:656; }","duration":"370.069594ms","start":"2026-08-20T20:30:12.42014Z","end":"2026-08-20T20:30:12.790209Z","steps":["trace[82786908] 'agreement among raft nodes before linearized reading' (duration: 369.882146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:12.790272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.596525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:12.790305Z","caller":"traceutil/trace.go:171","msg":"trace[1742600572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"357.644897ms","start":"2026-08-20T20:30:12.432652Z","end":"2026-08-20T20:30:12.790296Z","steps":["trace[1742600572] 'agreement among raft nodes before linearized reading' (duration: 357.598465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:12.790324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:12.432643Z","time spent":"357.677319ms","remote":"127.0.0.1:54332","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-08-20T20:30:12.790337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.454394ms","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-08-20T20:30:12.790367Z","caller":"traceutil/trace.go:171","msg":"trace[522293815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"365.500056ms","start":"2026-08-20T20:30:12.424851Z","end":"2026-08-20T20:30:12.790352Z","steps":["trace[522293815] 'agreement among raft nodes before linearized reading' (duration: 365.441323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:12.790385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:12.42484Z","time spent":"365.541038ms","remote":"127.0.0.1:54592","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-08-20T20:30:12.790262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:12.420132Z","time spent":"370.122867ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-20T20:30:12.790584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.150615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-20T20:30:12.790597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.246007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T20:30:12.790696Z","caller":"traceutil/trace.go:171","msg":"trace[1161434668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"371.377694ms","start":"2026-08-20T20:30:12.419289Z","end":"2026-08-20T20:30:12.790667Z","steps":["trace[1161434668] 'agreement among raft nodes before linearized reading' (duration: 371.101701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:12.790757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:12.419271Z","time spent":"371.470408ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-20T20:30:12.790823Z","caller":"traceutil/trace.go:171","msg":"trace[1460395597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"366.176466ms","start":"2026-08-20T20:30:12.424425Z","end":"2026-08-20T20:30:12.790602Z","steps":["trace[1460395597] 'agreement among raft nodes before linearized reading' (duration: 366.110533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:12.790874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:12.424345Z","time spent":"366.506681ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-20T20:30:14.234167Z","caller":"traceutil/trace.go:171","msg":"trace[1821263516] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"487.066974ms","start":"2026-08-20T20:30:13.747082Z","end":"2026-08-20T20:30:14.234149Z","steps":["trace[1821263516] 'process raft request' (duration: 486.952289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:14.234282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:13.747062Z","time spent":"487.160909ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:18.698802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.257383076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:18.698867Z","caller":"traceutil/trace.go:171","msg":"trace[722138020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"2.257485231s","start":"2026-08-20T20:30:16.441365Z","end":"2026-08-20T20:30:18.69885Z","steps":["trace[722138020] 'range keys from in-memory index tree' (duration: 2.257323223s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:18.698901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:16.441348Z","time spent":"2.257543893s","remote":"127.0.0.1:54326","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-08-20T20:30:18.699054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.258432931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:18.699136Z","caller":"traceutil/trace.go:171","msg":"trace[1848646635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"4.258562327s","start":"2026-08-20T20:30:14.440554Z","end":"2026-08-20T20:30:18.699116Z","steps":["trace[1848646635] 'range keys from in-memory index tree' (duration: 4.258347248s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:18.699178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:14.44054Z","time spent":"4.258628102s","remote":"127.0.0.1:54332","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-08-20T20:30:18.699734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.87413853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T20:30:18.699773Z","caller":"traceutil/trace.go:171","msg":"trace[31902804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:664; }","duration":"3.874190782s","start":"2026-08-20T20:30:14.825571Z","end":"2026-08-20T20:30:18.699762Z","steps":["trace[31902804] 'range keys from in-memory index tree' (duration: 3.874030815s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:18.699816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:14.825564Z","time spent":"3.874242635s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T20:30:18.699853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.905416771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-20T20:30:18.699893Z","caller":"traceutil/trace.go:171","msg":"trace[877995894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"3.905498755s","start":"2026-08-20T20:30:14.794383Z","end":"2026-08-20T20:30:18.699882Z","steps":["trace[877995894] 'range keys from in-memory index tree' (duration: 3.905212322s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:18.699766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.872362739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-20T20:30:18.700014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.874931235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-20T20:30:18.700092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.877220361s","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-08-20T20:30:18.700098Z","caller":"traceutil/trace.go:171","msg":"trace[1144242437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:664; }","duration":"3.875061651s","start":"2026-08-20T20:30:14.825019Z","end":"2026-08-20T20:30:18.700081Z","steps":["trace[1144242437] 'range keys from in-memory index tree' (duration: 3.87480049s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:18.700115Z","caller":"traceutil/trace.go:171","msg":"trace[309983402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"3.877281654s","start":"2026-08-20T20:30:14.822827Z","end":"2026-08-20T20:30:18.700109Z","steps":["trace[309983402] 'range keys from in-memory index tree' (duration: 3.877114456s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:18.699988Z","caller":"traceutil/trace.go:171","msg":"trace[186317798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"3.87260044s","start":"2026-08-20T20:30:14.827344Z","end":"2026-08-20T20:30:18.699945Z","steps":["trace[186317798] 'range keys from in-memory index tree' (duration: 3.872211132s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:18.69992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:14.794362Z","time spent":"3.905553368s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-20T20:30:18.700157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:14.825Z","time spent":"3.875143035s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-20T20:30:18.70019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:14.822804Z","time spent":"3.877341796s","remote":"127.0.0.1:54592","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-08-20T20:30:18.700226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:14.827322Z","time spent":"3.872865132s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T20:30:18.74006Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.074760492s","expected-duration":"1s"} {"level":"info","ts":"2026-08-20T20:30:18.740673Z","caller":"traceutil/trace.go:171","msg":"trace[2050894009] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"2.075483085s","start":"2026-08-20T20:30:16.665157Z","end":"2026-08-20T20:30:18.74064Z","steps":["trace[2050894009] 'process raft request' (duration: 2.075183001s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:18.741035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:16.665135Z","time spent":"2.075790219s","remote":"127.0.0.1:54592","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-08-20T20:30:18.809343Z","caller":"traceutil/trace.go:171","msg":"trace[583072471] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"362.774961ms","start":"2026-08-20T20:30:18.44655Z","end":"2026-08-20T20:30:18.809325Z","steps":["trace[583072471] 'process raft request' (duration: 362.687847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:18.809457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:18.446475Z","time spent":"362.925278ms","remote":"127.0.0.1:54404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:30:18.821592Z","caller":"traceutil/trace.go:171","msg":"trace[724733436] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"117.376503ms","start":"2026-08-20T20:30:18.70419Z","end":"2026-08-20T20:30:18.821567Z","steps":["trace[724733436] 'process raft request' (duration: 117.30009ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:18.821775Z","caller":"traceutil/trace.go:171","msg":"trace[873202759] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"118.212531ms","start":"2026-08-20T20:30:18.703504Z","end":"2026-08-20T20:30:18.821717Z","steps":["trace[873202759] 'process raft request' (duration: 105.81001ms)","trace[873202759] 'compare' (duration: 12.011293ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:18.821902Z","caller":"traceutil/trace.go:171","msg":"trace[1894686321] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"117.247547ms","start":"2026-08-20T20:30:18.704625Z","end":"2026-08-20T20:30:18.821873Z","steps":["trace[1894686321] 'process raft request' (duration: 116.906292ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:18.822123Z","caller":"traceutil/trace.go:171","msg":"trace[833079559] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:688; }","duration":"372.54636ms","start":"2026-08-20T20:30:18.449567Z","end":"2026-08-20T20:30:18.822113Z","steps":["trace[833079559] 'read index received' (duration: 290.767949ms)","trace[833079559] 'applied index is now lower than readState.Index' (duration: 81.777781ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:18.822159Z","caller":"traceutil/trace.go:171","msg":"trace[1687850281] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"117.474778ms","start":"2026-08-20T20:30:18.704668Z","end":"2026-08-20T20:30:18.822143Z","steps":["trace[1687850281] 'process raft request' (duration: 117.137352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:18.822185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.604024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:18.822216Z","caller":"traceutil/trace.go:171","msg":"trace[1542877146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"372.668717ms","start":"2026-08-20T20:30:18.449536Z","end":"2026-08-20T20:30:18.822204Z","steps":["trace[1542877146] 'agreement among raft nodes before linearized reading' (duration: 372.611724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:18.8223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:18.449523Z","time spent":"372.770961ms","remote":"127.0.0.1:54332","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-08-20T20:30:18.821967Z","caller":"traceutil/trace.go:171","msg":"trace[485734578] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"117.805733ms","start":"2026-08-20T20:30:18.704138Z","end":"2026-08-20T20:30:18.821944Z","steps":["trace[485734578] 'process raft request' (duration: 117.3113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:24.395283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.046627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:24.395363Z","caller":"traceutil/trace.go:171","msg":"trace[505127974] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:703; }","duration":"122.177793ms","start":"2026-08-20T20:30:24.273165Z","end":"2026-08-20T20:30:24.395343Z","steps":["trace[505127974] 'count revisions from in-memory index tree' (duration: 121.949803ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:32.113548Z","caller":"traceutil/trace.go:171","msg":"trace[1321699087] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"120.666238ms","start":"2026-08-20T20:30:31.992865Z","end":"2026-08-20T20:30:32.113531Z","steps":["trace[1321699087] 'read index received' (duration: 120.50098ms)","trace[1321699087] 'applied index is now lower than readState.Index' (duration: 164.608µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:32.113865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.954452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:32.11393Z","caller":"traceutil/trace.go:171","msg":"trace[1795299768] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:758; }","duration":"121.057017ms","start":"2026-08-20T20:30:31.992859Z","end":"2026-08-20T20:30:32.113916Z","steps":["trace[1795299768] 'agreement among raft nodes before linearized reading' (duration: 120.90545ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:32.113886Z","caller":"traceutil/trace.go:171","msg":"trace[407472652] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"274.318914ms","start":"2026-08-20T20:30:31.839553Z","end":"2026-08-20T20:30:32.113872Z","steps":["trace[407472652] 'process raft request' (duration: 273.872993ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:35.207375Z","caller":"traceutil/trace.go:171","msg":"trace[1745484107] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:805; }","duration":"202.837064ms","start":"2026-08-20T20:30:35.004511Z","end":"2026-08-20T20:30:35.207348Z","steps":["trace[1745484107] 'read index received' (duration: 39.418216ms)","trace[1745484107] 'applied index is now lower than readState.Index' (duration: 163.417618ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:35.207461Z","caller":"traceutil/trace.go:171","msg":"trace[888794341] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"227.558844ms","start":"2026-08-20T20:30:34.97989Z","end":"2026-08-20T20:30:35.207448Z","steps":["trace[888794341] 'process raft request' (duration: 220.213626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:35.207598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.064874ms","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-08-20T20:30:35.207668Z","caller":"traceutil/trace.go:171","msg":"trace[1100530547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"203.18184ms","start":"2026-08-20T20:30:35.004467Z","end":"2026-08-20T20:30:35.207649Z","steps":["trace[1100530547] 'agreement among raft nodes before linearized reading' (duration: 202.992241ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:35.207697Z","caller":"traceutil/trace.go:171","msg":"trace[234228411] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"227.681079ms","start":"2026-08-20T20:30:34.979984Z","end":"2026-08-20T20:30:35.207665Z","steps":["trace[234228411] 'process raft request' (duration: 227.249518ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:35.361126Z","caller":"traceutil/trace.go:171","msg":"trace[1124575626] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"149.259227ms","start":"2026-08-20T20:30:35.211843Z","end":"2026-08-20T20:30:35.361103Z","steps":["trace[1124575626] 'process raft request' (duration: 119.366321ms)","trace[1124575626] 'compare' (duration: 29.798271ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:36.172091Z","caller":"traceutil/trace.go:171","msg":"trace[343448864] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"289.884486ms","start":"2026-08-20T20:30:35.882183Z","end":"2026-08-20T20:30:36.172067Z","steps":["trace[343448864] 'read index received' (duration: 289.614273ms)","trace[343448864] 'applied index is now lower than readState.Index' (duration: 269.523µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:36.172167Z","caller":"traceutil/trace.go:171","msg":"trace[1206625057] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"321.457211ms","start":"2026-08-20T20:30:35.850667Z","end":"2026-08-20T20:30:36.172125Z","steps":["trace[1206625057] 'process raft request' (duration: 321.260862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:36.17223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.030423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:36.172311Z","caller":"traceutil/trace.go:171","msg":"trace[140435504] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:781; }","duration":"290.151759ms","start":"2026-08-20T20:30:35.882138Z","end":"2026-08-20T20:30:36.17229Z","steps":["trace[140435504] 'agreement among raft nodes before linearized reading' (duration: 290.011352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:36.17238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:35.850656Z","time spent":"321.605138ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:36.886927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:36.47292Z","time spent":"413.999168ms","remote":"127.0.0.1:51568","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-20T20:30:36.88731Z","caller":"traceutil/trace.go:171","msg":"trace[658599986] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"454.534309ms","start":"2026-08-20T20:30:36.43275Z","end":"2026-08-20T20:30:36.887284Z","steps":["trace[658599986] 'read index received' (duration: 372.113192ms)","trace[658599986] 'applied index is now lower than readState.Index' (duration: 82.419787ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:36.887399Z","caller":"traceutil/trace.go:171","msg":"trace[524773509] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"709.902427ms","start":"2026-08-20T20:30:36.177445Z","end":"2026-08-20T20:30:36.887348Z","steps":["trace[524773509] 'process raft request' (duration: 627.4757ms)","trace[524773509] 'compare' (duration: 81.956785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:36.887564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.780382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-20T20:30:36.887641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:36.177425Z","time spent":"710.090466ms","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4622,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:30:36.887674Z","caller":"traceutil/trace.go:171","msg":"trace[539016684] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:782; }","duration":"450.923159ms","start":"2026-08-20T20:30:36.436736Z","end":"2026-08-20T20:30:36.887659Z","steps":["trace[539016684] 'agreement among raft nodes before linearized reading' (duration: 450.72663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:36.887749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:36.436728Z","time spent":"450.985062ms","remote":"127.0.0.1:54820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-08-20T20:30:36.887933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.133918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:36.888066Z","caller":"traceutil/trace.go:171","msg":"trace[547919418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"455.296416ms","start":"2026-08-20T20:30:36.432714Z","end":"2026-08-20T20:30:36.88801Z","steps":["trace[547919418] 'agreement among raft nodes before linearized reading' (duration: 454.734719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:36.888154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:36.432698Z","time spent":"455.442623ms","remote":"127.0.0.1:54332","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-08-20T20:30:37.481061Z","caller":"traceutil/trace.go:171","msg":"trace[1785064273] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"502.342436ms","start":"2026-08-20T20:30:36.978692Z","end":"2026-08-20T20:30:37.481034Z","steps":["trace[1785064273] 'read index received' (duration: 449.943428ms)","trace[1785064273] 'applied index is now lower than readState.Index' (duration: 52.398388ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:37.481148Z","caller":"traceutil/trace.go:171","msg":"trace[130514772] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"582.659674ms","start":"2026-08-20T20:30:36.898481Z","end":"2026-08-20T20:30:37.48114Z","steps":["trace[130514772] 'process raft request' (duration: 530.13098ms)","trace[130514772] 'compare' (duration: 52.147136ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:37.48127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:36.898469Z","time spent":"582.734328ms","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T20:30:37.481563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.867171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T20:30:37.481618Z","caller":"traceutil/trace.go:171","msg":"trace[1183197943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"502.962535ms","start":"2026-08-20T20:30:36.978641Z","end":"2026-08-20T20:30:37.481604Z","steps":["trace[1183197943] 'agreement among raft nodes before linearized reading' (duration: 502.695102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:37.481706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:36.978621Z","time spent":"503.07444ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-20T20:30:37.510386Z","caller":"traceutil/trace.go:171","msg":"trace[1158788745] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"330.561423ms","start":"2026-08-20T20:30:37.179798Z","end":"2026-08-20T20:30:37.510359Z","steps":["trace[1158788745] 'process raft request' (duration: 330.429156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:37.510704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:37.179773Z","time spent":"330.690769ms","remote":"127.0.0.1:54570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:37.51072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.316588ms","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-08-20T20:30:37.510791Z","caller":"traceutil/trace.go:171","msg":"trace[360794128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"145.461864ms","start":"2026-08-20T20:30:37.365315Z","end":"2026-08-20T20:30:37.510777Z","steps":["trace[360794128] 'agreement among raft nodes before linearized reading' (duration: 145.109878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:37.511012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.924169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:30:37.511143Z","caller":"traceutil/trace.go:171","msg":"trace[2010661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"300.055716ms","start":"2026-08-20T20:30:37.211008Z","end":"2026-08-20T20:30:37.511063Z","steps":["trace[2010661] 'agreement among raft nodes before linearized reading' (duration: 299.824815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:37.51114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.716008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-20T20:30:37.51119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:37.21099Z","time spent":"300.189901ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-20T20:30:37.5112Z","caller":"traceutil/trace.go:171","msg":"trace[1247909118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"299.822833ms","start":"2026-08-20T20:30:37.211361Z","end":"2026-08-20T20:30:37.511184Z","steps":["trace[1247909118] 'agreement among raft nodes before linearized reading' (duration: 299.600693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:37.511265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.221331ms","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-08-20T20:30:37.511389Z","caller":"traceutil/trace.go:171","msg":"trace[981639720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"463.457422ms","start":"2026-08-20T20:30:37.047893Z","end":"2026-08-20T20:30:37.51135Z","steps":["trace[981639720] 'agreement among raft nodes before linearized reading' (duration: 463.070754ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:37.512246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:37.047873Z","time spent":"463.733775ms","remote":"127.0.0.1:54592","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-08-20T20:30:37.764318Z","caller":"traceutil/trace.go:171","msg":"trace[1898785589] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"253.887654ms","start":"2026-08-20T20:30:37.510398Z","end":"2026-08-20T20:30:37.764286Z","steps":["trace[1898785589] 'read index received' (duration: 226.149467ms)","trace[1898785589] 'applied index is now lower than readState.Index' (duration: 27.734437ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:37.764439Z","caller":"traceutil/trace.go:171","msg":"trace[1506182791] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"279.791613ms","start":"2026-08-20T20:30:37.484604Z","end":"2026-08-20T20:30:37.764395Z","steps":["trace[1506182791] 'process raft request' (duration: 251.92199ms)","trace[1506182791] 'compare' (duration: 27.626792ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:37.764643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.280959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T20:30:37.76481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.348694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:37.764841Z","caller":"traceutil/trace.go:171","msg":"trace[2124507847] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:785; }","duration":"274.396887ms","start":"2026-08-20T20:30:37.490438Z","end":"2026-08-20T20:30:37.764835Z","steps":["trace[2124507847] 'agreement among raft nodes before linearized reading' (duration: 274.2562ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:37.764839Z","caller":"traceutil/trace.go:171","msg":"trace[1457133942] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-rate-limit; range_end:; response_count:0; response_revision:785; }","duration":"279.53293ms","start":"2026-08-20T20:30:37.485253Z","end":"2026-08-20T20:30:37.764786Z","steps":["trace[1457133942] 'agreement among raft nodes before linearized reading' (duration: 279.150212ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:37.803328Z","caller":"traceutil/trace.go:171","msg":"trace[1157704693] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"287.254448ms","start":"2026-08-20T20:30:37.516024Z","end":"2026-08-20T20:30:37.803278Z","steps":["trace[1157704693] 'process raft request' (duration: 286.901651ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:37.80334Z","caller":"traceutil/trace.go:171","msg":"trace[539980180] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"287.230257ms","start":"2026-08-20T20:30:37.516088Z","end":"2026-08-20T20:30:37.803318Z","steps":["trace[539980180] 'process raft request' (duration: 286.87114ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:37.803424Z","caller":"traceutil/trace.go:171","msg":"trace[1082950655] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"287.422085ms","start":"2026-08-20T20:30:37.515996Z","end":"2026-08-20T20:30:37.803418Z","steps":["trace[1082950655] 'process raft request' (duration: 286.800256ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:37.803443Z","caller":"traceutil/trace.go:171","msg":"trace[1351238891] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"286.572354ms","start":"2026-08-20T20:30:37.516846Z","end":"2026-08-20T20:30:37.803419Z","steps":["trace[1351238891] 'process raft request' (duration: 286.140424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:38.134956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.913115ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T20:30:38.218554Z","caller":"traceutil/trace.go:171","msg":"trace[1956043466] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"448.679503ms","start":"2026-08-20T20:30:37.769783Z","end":"2026-08-20T20:30:38.218463Z","steps":["trace[1956043466] 'process raft request' (duration: 448.516475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:38.218722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:37.769766Z","time spent":"448.880632ms","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4445,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:30:38.400794Z","caller":"traceutil/trace.go:171","msg":"trace[355553726] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"175.730913ms","start":"2026-08-20T20:30:38.225034Z","end":"2026-08-20T20:30:38.400765Z","steps":["trace[355553726] 'process raft request' (duration: 84.535969ms)","trace[355553726] 'compare' (duration: 90.89852ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:38.734434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.082729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:30:38.734608Z","caller":"traceutil/trace.go:171","msg":"trace[994546255] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"302.162673ms","start":"2026-08-20T20:30:38.432424Z","end":"2026-08-20T20:30:38.734587Z","steps":["trace[994546255] 'read index received' (duration: 72.854623ms)","trace[994546255] 'applied index is now lower than readState.Index' (duration: 229.30632ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:38.73472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.29621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:38.734786Z","caller":"traceutil/trace.go:171","msg":"trace[1363990295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"302.401515ms","start":"2026-08-20T20:30:38.432371Z","end":"2026-08-20T20:30:38.734773Z","steps":["trace[1363990295] 'agreement among raft nodes before linearized reading' (duration: 302.284559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:38.734834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:38.432349Z","time spent":"302.473688ms","remote":"127.0.0.1:54332","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-08-20T20:30:38.734857Z","caller":"traceutil/trace.go:171","msg":"trace[1871525865] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"323.307797ms","start":"2026-08-20T20:30:38.411521Z","end":"2026-08-20T20:30:38.734829Z","steps":["trace[1871525865] 'process raft request' (duration: 93.741865ms)","trace[1871525865] 'compare' (duration: 228.866419ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:38.735019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:38.411505Z","time spent":"323.430453ms","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2963,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:30:39.719911Z","caller":"traceutil/trace.go:171","msg":"trace[847378082] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"370.790287ms","start":"2026-08-20T20:30:39.349095Z","end":"2026-08-20T20:30:39.719885Z","steps":["trace[847378082] 'process raft request' (duration: 370.552076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:39.720081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:39.349071Z","time spent":"370.922883ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:40.30896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158612440,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:30:40.810015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158612440,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:30:41.311159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158612440,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:30:41.811692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375817158612440,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:30:41.92471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951223732s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:41.92485Z","caller":"traceutil/trace.go:171","msg":"trace[1229261606] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:793; }","duration":"1.951377879s","start":"2026-08-20T20:30:39.973454Z","end":"2026-08-20T20:30:41.924832Z","steps":["trace[1229261606] 'range keys from in-memory index tree' (duration: 1.951204161s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:41.927289Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.154362981s","expected-duration":"1s"} {"level":"info","ts":"2026-08-20T20:30:41.92763Z","caller":"traceutil/trace.go:171","msg":"trace[1600258053] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"2.119123224s","start":"2026-08-20T20:30:39.808489Z","end":"2026-08-20T20:30:41.927612Z","steps":["trace[1600258053] 'read index received' (duration: 2.118946736s)","trace[1600258053] 'applied index is now lower than readState.Index' (duration: 175.798µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:41.92766Z","caller":"traceutil/trace.go:171","msg":"trace[401198645] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"2.154849344s","start":"2026-08-20T20:30:39.772786Z","end":"2026-08-20T20:30:41.927636Z","steps":["trace[401198645] 'process raft request' (duration: 2.154696977s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:41.927802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:39.772766Z","time spent":"2.154956659s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:41.927834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496478025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T20:30:41.927838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.119369156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-20T20:30:41.92789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276986296s","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-08-20T20:30:41.92792Z","caller":"traceutil/trace.go:171","msg":"trace[2055721735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"1.496631172s","start":"2026-08-20T20:30:40.431264Z","end":"2026-08-20T20:30:41.927896Z","steps":["trace[2055721735] 'agreement among raft nodes before linearized reading' (duration: 1.496534208s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:41.927959Z","caller":"traceutil/trace.go:171","msg":"trace[592142828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"2.119519192s","start":"2026-08-20T20:30:39.808409Z","end":"2026-08-20T20:30:41.927928Z","steps":["trace[592142828] 'agreement among raft nodes before linearized reading' (duration: 2.119326223s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:41.927979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:40.431249Z","time spent":"1.496723067s","remote":"127.0.0.1:54332","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-08-20T20:30:41.9279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.118869261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-20T20:30:41.927864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.119391067s","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":"warn","ts":"2026-08-20T20:30:41.927985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.119290891s","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-08-20T20:30:41.92803Z","caller":"traceutil/trace.go:171","msg":"trace[1005658198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"2.119023419s","start":"2026-08-20T20:30:39.808997Z","end":"2026-08-20T20:30:41.92802Z","steps":["trace[1005658198] 'agreement among raft nodes before linearized reading' (duration: 2.118808419s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:41.928071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:39.808982Z","time spent":"2.119077461s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-20T20:30:41.928067Z","caller":"traceutil/trace.go:171","msg":"trace[1144253055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"2.119430368s","start":"2026-08-20T20:30:39.808619Z","end":"2026-08-20T20:30:41.92805Z","steps":["trace[1144253055] 'agreement among raft nodes before linearized reading' (duration: 2.119161885s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:41.928043Z","caller":"traceutil/trace.go:171","msg":"trace[1693557197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"2.119619327s","start":"2026-08-20T20:30:39.808409Z","end":"2026-08-20T20:30:41.928029Z","steps":["trace[1693557197] 'agreement among raft nodes before linearized reading' (duration: 2.119280041s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:41.928121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:39.808585Z","time spent":"2.119527873s","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T20:30:41.928169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:39.808388Z","time spent":"2.119752623s","remote":"127.0.0.1:54592","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":"info","ts":"2026-08-20T20:30:41.927961Z","caller":"traceutil/trace.go:171","msg":"trace[1847297781] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:794; }","duration":"1.277096852s","start":"2026-08-20T20:30:40.650846Z","end":"2026-08-20T20:30:41.927943Z","steps":["trace[1847297781] 'agreement among raft nodes before linearized reading' (duration: 1.276983446s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:41.928258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:40.650825Z","time spent":"1.277420977s","remote":"127.0.0.1:54658","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-08-20T20:30:41.928072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:39.808388Z","time spent":"2.119619767s","remote":"127.0.0.1:54592","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":"info","ts":"2026-08-20T20:30:42.986323Z","caller":"traceutil/trace.go:171","msg":"trace[18071512] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"109.102672ms","start":"2026-08-20T20:30:42.877199Z","end":"2026-08-20T20:30:42.986302Z","steps":["trace[18071512] 'process raft request' (duration: 108.964005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:44.055562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.338894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-20T20:30:44.055601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.744586ms","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-08-20T20:30:44.05564Z","caller":"traceutil/trace.go:171","msg":"trace[106807147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"108.469801ms","start":"2026-08-20T20:30:43.947152Z","end":"2026-08-20T20:30:44.055622Z","steps":["trace[106807147] 'agreement among raft nodes before linearized reading' (duration: 108.225899ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:44.055678Z","caller":"traceutil/trace.go:171","msg":"trace[1961187424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"107.849551ms","start":"2026-08-20T20:30:43.947811Z","end":"2026-08-20T20:30:44.055661Z","steps":["trace[1961187424] 'agreement among raft nodes before linearized reading' (duration: 107.604699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:44.056005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.292682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:30:44.056079Z","caller":"traceutil/trace.go:171","msg":"trace[754718600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"108.408838ms","start":"2026-08-20T20:30:43.947654Z","end":"2026-08-20T20:30:44.056063Z","steps":["trace[754718600] 'agreement among raft nodes before linearized reading' (duration: 108.177787ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:44.991335Z","caller":"traceutil/trace.go:171","msg":"trace[255741653] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"104.584648ms","start":"2026-08-20T20:30:44.886722Z","end":"2026-08-20T20:30:44.991307Z","steps":["trace[255741653] 'process raft request' (duration: 104.4294ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:45.207461Z","caller":"traceutil/trace.go:171","msg":"trace[1994649590] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"143.760511ms","start":"2026-08-20T20:30:45.063659Z","end":"2026-08-20T20:30:45.207419Z","steps":["trace[1994649590] 'process raft request' (duration: 143.542621ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:45.558695Z","caller":"traceutil/trace.go:171","msg":"trace[1171901788] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"126.416719ms","start":"2026-08-20T20:30:45.432227Z","end":"2026-08-20T20:30:45.558644Z","steps":["trace[1171901788] 'read index received' (duration: 68.513004ms)","trace[1171901788] 'applied index is now lower than readState.Index' (duration: 57.902835ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:45.558819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.582338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:30:45.558801Z","caller":"traceutil/trace.go:171","msg":"trace[526759354] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"342.019904ms","start":"2026-08-20T20:30:45.216746Z","end":"2026-08-20T20:30:45.558766Z","steps":["trace[526759354] 'process raft request' (duration: 284.109469ms)","trace[526759354] 'compare' (duration: 57.438833ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:45.558847Z","caller":"traceutil/trace.go:171","msg":"trace[408207971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"126.660382ms","start":"2026-08-20T20:30:45.432178Z","end":"2026-08-20T20:30:45.558839Z","steps":["trace[408207971] 'agreement among raft nodes before linearized reading' (duration: 126.567487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:45.558939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:30:45.21672Z","time spent":"342.14636ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:30:47.035348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.607459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:30:47.035509Z","caller":"traceutil/trace.go:171","msg":"trace[1288624533] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:882; }","duration":"203.002643ms","start":"2026-08-20T20:30:46.832492Z","end":"2026-08-20T20:30:47.035495Z","steps":["trace[1288624533] 'read index received' (duration: 52.180114ms)","trace[1288624533] 'applied index is now lower than readState.Index' (duration: 150.821498ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:47.035598Z","caller":"traceutil/trace.go:171","msg":"trace[1187903266] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"207.429906ms","start":"2026-08-20T20:30:46.828158Z","end":"2026-08-20T20:30:47.035588Z","steps":["trace[1187903266] 'process raft request' (duration: 56.483822ms)","trace[1187903266] 'compare' (duration: 150.420119ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:47.035724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.211753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-08-20T20:30:47.035788Z","caller":"traceutil/trace.go:171","msg":"trace[596858396] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:852; }","duration":"203.314369ms","start":"2026-08-20T20:30:46.832457Z","end":"2026-08-20T20:30:47.035772Z","steps":["trace[596858396] 'agreement among raft nodes before linearized reading' (duration: 203.133959ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:47.035939Z","caller":"traceutil/trace.go:171","msg":"trace[1576103449] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"204.323377ms","start":"2026-08-20T20:30:46.831607Z","end":"2026-08-20T20:30:47.03593Z","steps":["trace[1576103449] 'process raft request' (duration: 203.823312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:47.03604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.623704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-rt2wf\" ","response":"range_response_count:1 size:5393"} {"level":"info","ts":"2026-08-20T20:30:47.036074Z","caller":"traceutil/trace.go:171","msg":"trace[1659379414] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-rt2wf; range_end:; response_count:1; response_revision:852; }","duration":"148.683177ms","start":"2026-08-20T20:30:46.887382Z","end":"2026-08-20T20:30:47.036065Z","steps":["trace[1659379414] 'agreement among raft nodes before linearized reading' (duration: 148.597863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:30:51.184313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.789961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:30:51.184408Z","caller":"traceutil/trace.go:171","msg":"trace[1075195360] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"147.879073ms","start":"2026-08-20T20:30:51.036516Z","end":"2026-08-20T20:30:51.184395Z","steps":["trace[1075195360] 'read index received' (duration: 45.940485ms)","trace[1075195360] 'applied index is now lower than readState.Index' (duration: 101.937818ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:30:51.184484Z","caller":"traceutil/trace.go:171","msg":"trace[1234182471] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"148.243551ms","start":"2026-08-20T20:30:51.036192Z","end":"2026-08-20T20:30:51.184435Z","steps":["trace[1234182471] 'process raft request' (duration: 46.276072ms)","trace[1234182471] 'compare' (duration: 101.670674ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:51.184552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.030061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-nncqd\" ","response":"range_response_count:1 size:4869"} {"level":"info","ts":"2026-08-20T20:30:51.18458Z","caller":"traceutil/trace.go:171","msg":"trace[326817908] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-nncqd; range_end:; response_count:1; response_revision:898; }","duration":"148.089203ms","start":"2026-08-20T20:30:51.036484Z","end":"2026-08-20T20:30:51.184573Z","steps":["trace[326817908] 'agreement among raft nodes before linearized reading' (duration: 147.943196ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:51.235165Z","caller":"traceutil/trace.go:171","msg":"trace[1130739667] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"183.489223ms","start":"2026-08-20T20:30:51.051652Z","end":"2026-08-20T20:30:51.235141Z","steps":["trace[1130739667] 'process raft request' (duration: 183.433629ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:51.235239Z","caller":"traceutil/trace.go:171","msg":"trace[1942229166] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"194.905333ms","start":"2026-08-20T20:30:51.040302Z","end":"2026-08-20T20:30:51.235208Z","steps":["trace[1942229166] 'process raft request' (duration: 194.639229ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:51.30857Z","caller":"traceutil/trace.go:171","msg":"trace[799452459] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"122.266069ms","start":"2026-08-20T20:30:51.186281Z","end":"2026-08-20T20:30:51.308547Z","steps":["trace[799452459] 'process raft request' (duration: 117.635025ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:51.495264Z","caller":"traceutil/trace.go:171","msg":"trace[1449904426] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"106.94526ms","start":"2026-08-20T20:30:51.388289Z","end":"2026-08-20T20:30:51.495235Z","steps":["trace[1449904426] 'read index received' (duration: 98.44931ms)","trace[1449904426] 'applied index is now lower than readState.Index' (duration: 8.49409ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:30:51.495529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.227083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3867"} {"level":"info","ts":"2026-08-20T20:30:51.495591Z","caller":"traceutil/trace.go:171","msg":"trace[1203010982] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:907; }","duration":"107.339138ms","start":"2026-08-20T20:30:51.388237Z","end":"2026-08-20T20:30:51.495576Z","steps":["trace[1203010982] 'agreement among raft nodes before linearized reading' (duration: 107.120118ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:30:51.495752Z","caller":"traceutil/trace.go:171","msg":"trace[1688005961] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"178.623728ms","start":"2026-08-20T20:30:51.317109Z","end":"2026-08-20T20:30:51.495733Z","steps":["trace[1688005961] 'process raft request' (duration: 169.692116ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:03.420728Z","caller":"traceutil/trace.go:171","msg":"trace[283988813] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"134.6797ms","start":"2026-08-20T20:31:03.286017Z","end":"2026-08-20T20:31:03.420696Z","steps":["trace[283988813] 'process raft request' (duration: 134.421817ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:04.326325Z","caller":"traceutil/trace.go:171","msg":"trace[1418284645] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"146.714933ms","start":"2026-08-20T20:31:04.179583Z","end":"2026-08-20T20:31:04.326298Z","steps":["trace[1418284645] 'process raft request' (duration: 142.166021ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:04.361476Z","caller":"traceutil/trace.go:171","msg":"trace[1024473507] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1031; }","duration":"178.576139ms","start":"2026-08-20T20:31:04.182876Z","end":"2026-08-20T20:31:04.361452Z","steps":["trace[1024473507] 'read index received' (duration: 138.968035ms)","trace[1024473507] 'applied index is now lower than readState.Index' (duration: 39.606664ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:04.361607Z","caller":"traceutil/trace.go:171","msg":"trace[301790975] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"180.47065ms","start":"2026-08-20T20:31:04.181108Z","end":"2026-08-20T20:31:04.361578Z","steps":["trace[301790975] 'process raft request' (duration: 180.225348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:04.36167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.785979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-20T20:31:04.361696Z","caller":"traceutil/trace.go:171","msg":"trace[662757581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"178.866372ms","start":"2026-08-20T20:31:04.182822Z","end":"2026-08-20T20:31:04.361689Z","steps":["trace[662757581] 'agreement among raft nodes before linearized reading' (duration: 178.744377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:04.361878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.939597ms","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-08-20T20:31:04.361979Z","caller":"traceutil/trace.go:171","msg":"trace[1910889888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"178.077544ms","start":"2026-08-20T20:31:04.18388Z","end":"2026-08-20T20:31:04.361958Z","steps":["trace[1910889888] 'agreement among raft nodes before linearized reading' (duration: 177.779119ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:22.411939Z","caller":"traceutil/trace.go:171","msg":"trace[1460231304] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"164.943486ms","start":"2026-08-20T20:31:22.246964Z","end":"2026-08-20T20:31:22.411907Z","steps":["trace[1460231304] 'process raft request' (duration: 164.635751ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:22.881564Z","caller":"traceutil/trace.go:171","msg":"trace[699007053] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"380.6013ms","start":"2026-08-20T20:31:22.500931Z","end":"2026-08-20T20:31:22.881533Z","steps":["trace[699007053] 'read index received' (duration: 335.843797ms)","trace[699007053] 'applied index is now lower than readState.Index' (duration: 44.756013ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:22.881674Z","caller":"traceutil/trace.go:171","msg":"trace[909920825] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"394.5552ms","start":"2026-08-20T20:31:22.487082Z","end":"2026-08-20T20:31:22.881637Z","steps":["trace[909920825] 'process raft request' (duration: 349.728354ms)","trace[909920825] 'compare' (duration: 44.560413ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:22.881849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:22.487059Z","time spent":"394.687877ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:31:22.882203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.222321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:31:22.882432Z","caller":"traceutil/trace.go:171","msg":"trace[1149069284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1060; }","duration":"381.40156ms","start":"2026-08-20T20:31:22.500911Z","end":"2026-08-20T20:31:22.882312Z","steps":["trace[1149069284] 'agreement among raft nodes before linearized reading' (duration: 380.722446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:22.882547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:22.500903Z","time spent":"381.605089ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-20T20:31:23.216225Z","caller":"traceutil/trace.go:171","msg":"trace[436666317] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"214.970186ms","start":"2026-08-20T20:31:23.001234Z","end":"2026-08-20T20:31:23.216205Z","steps":["trace[436666317] 'process raft request' (duration: 214.831859ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:27.028615Z","caller":"traceutil/trace.go:171","msg":"trace[1547000442] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"123.624008ms","start":"2026-08-20T20:31:26.904975Z","end":"2026-08-20T20:31:27.028599Z","steps":["trace[1547000442] 'read index received' (duration: 123.477241ms)","trace[1547000442] 'applied index is now lower than readState.Index' (duration: 146.207µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:27.028721Z","caller":"traceutil/trace.go:171","msg":"trace[408102017] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"130.479178ms","start":"2026-08-20T20:31:26.898224Z","end":"2026-08-20T20:31:27.028704Z","steps":["trace[408102017] 'process raft request' (duration: 130.275388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:27.028782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.789836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:31:27.028798Z","caller":"traceutil/trace.go:171","msg":"trace[1374597766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"123.843059ms","start":"2026-08-20T20:31:26.90495Z","end":"2026-08-20T20:31:27.028793Z","steps":["trace[1374597766] 'agreement among raft nodes before linearized reading' (duration: 123.716193ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:31.210327Z","caller":"traceutil/trace.go:171","msg":"trace[871065152] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"146.479ms","start":"2026-08-20T20:31:31.063817Z","end":"2026-08-20T20:31:31.210296Z","steps":["trace[871065152] 'process raft request' (duration: 99.866323ms)","trace[871065152] 'compare' (duration: 46.160455ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:42.788594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.775702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-20T20:31:42.788632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.4739ms","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-08-20T20:31:42.788684Z","caller":"traceutil/trace.go:171","msg":"trace[952333078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"184.9198ms","start":"2026-08-20T20:31:42.603743Z","end":"2026-08-20T20:31:42.788663Z","steps":["trace[952333078] 'range keys from in-memory index tree' (duration: 184.585252ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:42.788717Z","caller":"traceutil/trace.go:171","msg":"trace[170820630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1124; }","duration":"100.611037ms","start":"2026-08-20T20:31:42.688087Z","end":"2026-08-20T20:31:42.788698Z","steps":["trace[170820630] 'range keys from in-memory index tree' (duration: 100.26949ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:47.00986Z","caller":"traceutil/trace.go:171","msg":"trace[126299831] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"106.262909ms","start":"2026-08-20T20:31:46.903562Z","end":"2026-08-20T20:31:47.009825Z","steps":["trace[126299831] 'process raft request' (duration: 50.695557ms)","trace[126299831] 'compare' (duration: 55.426135ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:47.337023Z","caller":"traceutil/trace.go:171","msg":"trace[136469029] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"107.03418ms","start":"2026-08-20T20:31:47.229971Z","end":"2026-08-20T20:31:47.337005Z","steps":["trace[136469029] 'read index received' (duration: 43.183863ms)","trace[136469029] 'applied index is now lower than readState.Index' (duration: 63.849427ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:47.337286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.293493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-20T20:31:47.337362Z","caller":"traceutil/trace.go:171","msg":"trace[1631005251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1139; }","duration":"107.437611ms","start":"2026-08-20T20:31:47.229907Z","end":"2026-08-20T20:31:47.337345Z","steps":["trace[1631005251] 'agreement among raft nodes before linearized reading' (duration: 107.176418ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:47.825378Z","caller":"traceutil/trace.go:171","msg":"trace[898214747] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"172.625044ms","start":"2026-08-20T20:31:47.652733Z","end":"2026-08-20T20:31:47.825358Z","steps":["trace[898214747] 'process raft request' (duration: 172.448527ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:55.003018Z","caller":"traceutil/trace.go:171","msg":"trace[1584310060] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"327.544802ms","start":"2026-08-20T20:31:54.675434Z","end":"2026-08-20T20:31:55.002979Z","steps":["trace[1584310060] 'process raft request' (duration: 327.225604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:55.003251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:54.675405Z","time spent":"327.735ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:31:55.223327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.845167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:31:55.223442Z","caller":"traceutil/trace.go:171","msg":"trace[273035353] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"163.545501ms","start":"2026-08-20T20:31:55.059881Z","end":"2026-08-20T20:31:55.223426Z","steps":["trace[273035353] 'read index received' (duration: 34.02µs)","trace[273035353] 'applied index is now lower than readState.Index' (duration: 163.510201ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:55.223572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.688421ms","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-08-20T20:31:55.223615Z","caller":"traceutil/trace.go:171","msg":"trace[979096949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"163.75066ms","start":"2026-08-20T20:31:55.05984Z","end":"2026-08-20T20:31:55.223591Z","steps":["trace[979096949] 'agreement among raft nodes before linearized reading' (duration: 163.631801ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:55.223718Z","caller":"traceutil/trace.go:171","msg":"trace[429110148] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"216.417072ms","start":"2026-08-20T20:31:55.007276Z","end":"2026-08-20T20:31:55.223694Z","steps":["trace[429110148] 'compare' (duration: 206.721348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:55.491389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.003807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:31:55.491529Z","caller":"traceutil/trace.go:171","msg":"trace[585297960] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"263.421052ms","start":"2026-08-20T20:31:55.228089Z","end":"2026-08-20T20:31:55.49151Z","steps":["trace[585297960] 'process raft request' (duration: 105.225005ms)","trace[585297960] 'compare' (duration: 157.920147ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:55.844606Z","caller":"traceutil/trace.go:171","msg":"trace[1512840724] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"349.434517ms","start":"2026-08-20T20:31:55.49514Z","end":"2026-08-20T20:31:55.844575Z","steps":["trace[1512840724] 'process raft request' (duration: 349.057858ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:55.844705Z","caller":"traceutil/trace.go:171","msg":"trace[841842945] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"349.599456ms","start":"2026-08-20T20:31:55.49508Z","end":"2026-08-20T20:31:55.844679Z","steps":["trace[841842945] 'process raft request' (duration: 293.772728ms)","trace[841842945] 'compare' (duration: 54.901221ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:55.84477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:55.49513Z","time spent":"349.547057ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:31:55.844815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:55.495055Z","time spent":"349.700065ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:32:02.047064Z","caller":"traceutil/trace.go:171","msg":"trace[1434099808] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"129.432322ms","start":"2026-08-20T20:32:01.917605Z","end":"2026-08-20T20:32:02.047037Z","steps":["trace[1434099808] 'process raft request' (duration: 61.181112ms)","trace[1434099808] 'compare' (duration: 68.12278ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:02.240186Z","caller":"traceutil/trace.go:171","msg":"trace[79568628] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"164.097242ms","start":"2026-08-20T20:32:02.07604Z","end":"2026-08-20T20:32:02.240138Z","steps":["trace[79568628] 'read index received' (duration: 155.12243ms)","trace[79568628] 'applied index is now lower than readState.Index' (duration: 8.973712ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:02.240452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.922882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:02.240522Z","caller":"traceutil/trace.go:171","msg":"trace[1978405577] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1188; }","duration":"140.057552ms","start":"2026-08-20T20:32:02.100452Z","end":"2026-08-20T20:32:02.24051Z","steps":["trace[1978405577] 'agreement among raft nodes before linearized reading' (duration: 139.888552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:02.240468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.424452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T20:32:02.240616Z","caller":"traceutil/trace.go:171","msg":"trace[1441442006] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:1188; }","duration":"164.581031ms","start":"2026-08-20T20:32:02.076024Z","end":"2026-08-20T20:32:02.240605Z","steps":["trace[1441442006] 'agreement among raft nodes before linearized reading' (duration: 164.296942ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:02.240291Z","caller":"traceutil/trace.go:171","msg":"trace[1669872088] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"286.081351ms","start":"2026-08-20T20:32:01.954136Z","end":"2026-08-20T20:32:02.240217Z","steps":["trace[1669872088] 'process raft request' (duration: 277.130209ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:02.44329Z","caller":"traceutil/trace.go:171","msg":"trace[653433783] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"130.264011ms","start":"2026-08-20T20:32:02.312974Z","end":"2026-08-20T20:32:02.443238Z","steps":["trace[653433783] 'process raft request' (duration: 76.282243ms)","trace[653433783] 'compare' (duration: 53.797788ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:09.574202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.544405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:09.57428Z","caller":"traceutil/trace.go:171","msg":"trace[1218133462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"143.654365ms","start":"2026-08-20T20:32:09.430609Z","end":"2026-08-20T20:32:09.574263Z","steps":["trace[1218133462] 'range keys from in-memory index tree' (duration: 143.479475ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:09.728617Z","caller":"traceutil/trace.go:171","msg":"trace[1906905074] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"121.117174ms","start":"2026-08-20T20:32:09.607469Z","end":"2026-08-20T20:32:09.728586Z","steps":["trace[1906905074] 'process raft request' (duration: 120.894144ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:10.09324Z","caller":"traceutil/trace.go:171","msg":"trace[2125861840] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"130.245029ms","start":"2026-08-20T20:32:09.962967Z","end":"2026-08-20T20:32:10.093212Z","steps":["trace[2125861840] 'process raft request' (duration: 130.060749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:10.394019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.271017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:32:10.394305Z","caller":"traceutil/trace.go:171","msg":"trace[385659139] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"296.128235ms","start":"2026-08-20T20:32:10.098137Z","end":"2026-08-20T20:32:10.394265Z","steps":["trace[385659139] 'process raft request' (duration: 47.335568ms)","trace[385659139] 'compare' (duration: 248.131647ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:10.425109Z","caller":"traceutil/trace.go:171","msg":"trace[1188383520] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"160.465347ms","start":"2026-08-20T20:32:10.264622Z","end":"2026-08-20T20:32:10.425088Z","steps":["trace[1188383520] 'process raft request' (duration: 160.365527ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:11.49988Z","caller":"traceutil/trace.go:171","msg":"trace[1725289990] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"171.947115ms","start":"2026-08-20T20:32:11.32791Z","end":"2026-08-20T20:32:11.499857Z","steps":["trace[1725289990] 'process raft request' (duration: 171.835095ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:11.512817Z","caller":"traceutil/trace.go:171","msg":"trace[1867983821] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"164.474798ms","start":"2026-08-20T20:32:11.348306Z","end":"2026-08-20T20:32:11.51278Z","steps":["trace[1867983821] 'process raft request' (duration: 163.356126ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:12.227342Z","caller":"traceutil/trace.go:171","msg":"trace[291474349] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"126.966689ms","start":"2026-08-20T20:32:12.100346Z","end":"2026-08-20T20:32:12.227312Z","steps":["trace[291474349] 'process raft request' (duration: 63.397244ms)","trace[291474349] 'compare' (duration: 63.325904ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:14.194463Z","caller":"traceutil/trace.go:171","msg":"trace[1146440080] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"105.003726ms","start":"2026-08-20T20:32:14.089428Z","end":"2026-08-20T20:32:14.194432Z","steps":["trace[1146440080] 'process raft request' (duration: 104.784455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:14.574166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.07754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:32:14.574251Z","caller":"traceutil/trace.go:171","msg":"trace[349187854] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"299.096765ms","start":"2026-08-20T20:32:14.275141Z","end":"2026-08-20T20:32:14.574238Z","steps":["trace[349187854] 'read index received' (duration: 108.794205ms)","trace[349187854] 'applied index is now lower than readState.Index' (duration: 190.30152ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:14.574319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.170195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:14.574341Z","caller":"traceutil/trace.go:171","msg":"trace[1936530158] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1227; }","duration":"299.240525ms","start":"2026-08-20T20:32:14.275094Z","end":"2026-08-20T20:32:14.574335Z","steps":["trace[1936530158] 'agreement among raft nodes before linearized reading' (duration: 299.193515ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:14.574307Z","caller":"traceutil/trace.go:171","msg":"trace[1641724256] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"338.84556ms","start":"2026-08-20T20:32:14.23542Z","end":"2026-08-20T20:32:14.574266Z","steps":["trace[1641724256] 'process raft request' (duration: 148.61114ms)","trace[1641724256] 'compare' (duration: 189.99806ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:14.574469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:14.235392Z","time spent":"339.00329ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:14.586078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.495822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:14.586165Z","caller":"traceutil/trace.go:171","msg":"trace[655069504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"154.615453ms","start":"2026-08-20T20:32:14.43153Z","end":"2026-08-20T20:32:14.586145Z","steps":["trace[655069504] 'agreement among raft nodes before linearized reading' (duration: 154.464083ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:14.586142Z","caller":"traceutil/trace.go:171","msg":"trace[594172361] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"129.527918ms","start":"2026-08-20T20:32:14.456576Z","end":"2026-08-20T20:32:14.586104Z","steps":["trace[594172361] 'process raft request' (duration: 129.232348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:14.586243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.665671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:32:14.586304Z","caller":"traceutil/trace.go:171","msg":"trace[1958545644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"148.760301ms","start":"2026-08-20T20:32:14.43753Z","end":"2026-08-20T20:32:14.58629Z","steps":["trace[1958545644] 'agreement among raft nodes before linearized reading' (duration: 148.56547ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:19.678402Z","caller":"traceutil/trace.go:171","msg":"trace[1751033742] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"142.500641ms","start":"2026-08-20T20:32:19.535876Z","end":"2026-08-20T20:32:19.678377Z","steps":["trace[1751033742] 'process raft request' (duration: 142.321701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:19.928688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.252519ms","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-08-20T20:32:19.928739Z","caller":"traceutil/trace.go:171","msg":"trace[1827251480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"139.349059ms","start":"2026-08-20T20:32:19.78938Z","end":"2026-08-20T20:32:19.928729Z","steps":["trace[1827251480] 'range keys from in-memory index tree' (duration: 139.098599ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:26.083821Z","caller":"traceutil/trace.go:171","msg":"trace[1766391360] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"109.182044ms","start":"2026-08-20T20:32:25.974623Z","end":"2026-08-20T20:32:26.083805Z","steps":["trace[1766391360] 'read index received' (duration: 109.015263ms)","trace[1766391360] 'applied index is now lower than readState.Index' (duration: 166.031µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:26.083943Z","caller":"traceutil/trace.go:171","msg":"trace[371744772] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"375.555297ms","start":"2026-08-20T20:32:25.708339Z","end":"2026-08-20T20:32:26.083894Z","steps":["trace[371744772] 'process raft request' (duration: 375.364756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:26.084013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.381685ms","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-08-20T20:32:26.084083Z","caller":"traceutil/trace.go:171","msg":"trace[2086086137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1276; }","duration":"109.498395ms","start":"2026-08-20T20:32:25.974573Z","end":"2026-08-20T20:32:26.084072Z","steps":["trace[2086086137] 'agreement among raft nodes before linearized reading' (duration: 109.341195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:26.084173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:25.708308Z","time spent":"375.765898ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:32:26.227474Z","caller":"traceutil/trace.go:171","msg":"trace[1807145193] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"137.961335ms","start":"2026-08-20T20:32:26.089474Z","end":"2026-08-20T20:32:26.227436Z","steps":["trace[1807145193] 'process raft request' (duration: 93.768252ms)","trace[1807145193] 'compare' (duration: 44.028583ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:26.54916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.867623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:26.549218Z","caller":"traceutil/trace.go:171","msg":"trace[210173077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"115.984954ms","start":"2026-08-20T20:32:26.433219Z","end":"2026-08-20T20:32:26.549204Z","steps":["trace[210173077] 'range keys from in-memory index tree' (duration: 115.764402ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:26.881715Z","caller":"traceutil/trace.go:171","msg":"trace[228639799] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1331; }","duration":"228.613349ms","start":"2026-08-20T20:32:26.653076Z","end":"2026-08-20T20:32:26.881689Z","steps":["trace[228639799] 'read index received' (duration: 82.084952ms)","trace[228639799] 'applied index is now lower than readState.Index' (duration: 146.527447ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:26.881875Z","caller":"traceutil/trace.go:171","msg":"trace[266779250] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"235.402919ms","start":"2026-08-20T20:32:26.646449Z","end":"2026-08-20T20:32:26.881852Z","steps":["trace[266779250] 'process raft request' (duration: 163.096386ms)","trace[266779250] 'compare' (duration: 72.024391ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:26.882181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.117643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:32:26.882235Z","caller":"traceutil/trace.go:171","msg":"trace[1191474529] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1279; }","duration":"229.200853ms","start":"2026-08-20T20:32:26.65302Z","end":"2026-08-20T20:32:26.88222Z","steps":["trace[1191474529] 'agreement among raft nodes before linearized reading' (duration: 229.038932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:27.205905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.838055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-810c620f-fc63-4760-bb66-bdf1f4646ae5\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-08-20T20:32:27.206068Z","caller":"traceutil/trace.go:171","msg":"trace[1475985596] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-810c620f-fc63-4760-bb66-bdf1f4646ae5; range_end:; response_count:1; response_revision:1280; }","duration":"117.051496ms","start":"2026-08-20T20:32:27.088994Z","end":"2026-08-20T20:32:27.206046Z","steps":["trace[1475985596] 'range keys from in-memory index tree' (duration: 116.667575ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:27.37593Z","caller":"traceutil/trace.go:171","msg":"trace[421265739] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"162.050529ms","start":"2026-08-20T20:32:27.213853Z","end":"2026-08-20T20:32:27.375904Z","steps":["trace[421265739] 'process raft request' (duration: 161.918598ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:28.261962Z","caller":"traceutil/trace.go:171","msg":"trace[1519072947] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"948.847466ms","start":"2026-08-20T20:32:27.313088Z","end":"2026-08-20T20:32:28.261935Z","steps":["trace[1519072947] 'read index received' (duration: 62.795565ms)","trace[1519072947] 'applied index is now lower than readState.Index' (duration: 886.050421ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:28.262249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.151638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T20:32:28.262338Z","caller":"traceutil/trace.go:171","msg":"trace[862575301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"949.301098ms","start":"2026-08-20T20:32:27.313015Z","end":"2026-08-20T20:32:28.262316Z","steps":["trace[862575301] 'agreement among raft nodes before linearized reading' (duration: 949.040217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.262367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.964527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-20T20:32:28.262413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:27.312994Z","time spent":"949.386899ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-20T20:32:28.262551Z","caller":"traceutil/trace.go:171","msg":"trace[1895305997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"174.213088ms","start":"2026-08-20T20:32:28.088295Z","end":"2026-08-20T20:32:28.262508Z","steps":["trace[1895305997] 'agreement among raft nodes before linearized reading' (duration: 173.806866ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:28.690548Z","caller":"traceutil/trace.go:171","msg":"trace[1234769358] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"423.407431ms","start":"2026-08-20T20:32:28.267091Z","end":"2026-08-20T20:32:28.690499Z","steps":["trace[1234769358] 'process raft request' (duration: 423.25326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.690688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:28.267066Z","time spent":"423.552132ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:32:28.863465Z","caller":"traceutil/trace.go:171","msg":"trace[1414753588] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"596.074828ms","start":"2026-08-20T20:32:28.267368Z","end":"2026-08-20T20:32:28.863442Z","steps":["trace[1414753588] 'process raft request' (duration: 587.978656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.863612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:28.267346Z","time spent":"596.193951ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:32:28.863661Z","caller":"traceutil/trace.go:171","msg":"trace[1892610390] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1336; }","duration":"430.624388ms","start":"2026-08-20T20:32:28.433004Z","end":"2026-08-20T20:32:28.863628Z","steps":["trace[1892610390] 'read index received' (duration: 257.316896ms)","trace[1892610390] 'applied index is now lower than readState.Index' (duration: 173.305472ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:28.863725Z","caller":"traceutil/trace.go:171","msg":"trace[901256137] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"595.488905ms","start":"2026-08-20T20:32:28.268176Z","end":"2026-08-20T20:32:28.863664Z","steps":["trace[901256137] 'process raft request' (duration: 595.232103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.863847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.830939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:28.863921Z","caller":"traceutil/trace.go:171","msg":"trace[181868251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"430.951691ms","start":"2026-08-20T20:32:28.432956Z","end":"2026-08-20T20:32:28.863907Z","steps":["trace[181868251] 'agreement among raft nodes before linearized reading' (duration: 430.808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.863958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:28.432932Z","time spent":"431.020231ms","remote":"127.0.0.1:54332","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-08-20T20:32:28.864041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:28.268167Z","time spent":"595.737517ms","remote":"127.0.0.1:54592","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-08-20T20:32:28.916327Z","caller":"traceutil/trace.go:171","msg":"trace[1609011708] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"173.912166ms","start":"2026-08-20T20:32:28.742386Z","end":"2026-08-20T20:32:28.916298Z","steps":["trace[1609011708] 'process raft request' (duration: 173.610535ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:29.029151Z","caller":"traceutil/trace.go:171","msg":"trace[1001036432] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"108.035148ms","start":"2026-08-20T20:32:28.920162Z","end":"2026-08-20T20:32:29.028197Z","steps":["trace[1001036432] 'process raft request' (duration: 50.733384ms)","trace[1001036432] 'compare' (duration: 57.104972ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:35.035785Z","caller":"traceutil/trace.go:171","msg":"trace[839926933] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"142.218885ms","start":"2026-08-20T20:32:34.893522Z","end":"2026-08-20T20:32:35.035741Z","steps":["trace[839926933] 'process raft request' (duration: 115.283382ms)","trace[839926933] 'compare' (duration: 26.181117ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:35.036029Z","caller":"traceutil/trace.go:171","msg":"trace[1008765058] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"129.200876ms","start":"2026-08-20T20:32:34.906797Z","end":"2026-08-20T20:32:35.035998Z","steps":["trace[1008765058] 'process raft request' (duration: 128.572311ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:35.054676Z","caller":"traceutil/trace.go:171","msg":"trace[575415827] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"121.58387ms","start":"2026-08-20T20:32:34.933063Z","end":"2026-08-20T20:32:35.054646Z","steps":["trace[575415827] 'process raft request' (duration: 121.390918ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:35.212621Z","caller":"traceutil/trace.go:171","msg":"trace[1385475679] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"129.146955ms","start":"2026-08-20T20:32:35.083454Z","end":"2026-08-20T20:32:35.212601Z","steps":["trace[1385475679] 'read index received' (duration: 61.621262ms)","trace[1385475679] 'applied index is now lower than readState.Index' (duration: 67.524873ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:35.212766Z","caller":"traceutil/trace.go:171","msg":"trace[1881436883] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"154.06333ms","start":"2026-08-20T20:32:35.058651Z","end":"2026-08-20T20:32:35.212714Z","steps":["trace[1881436883] 'process raft request' (duration: 86.524987ms)","trace[1881436883] 'compare' (duration: 67.301891ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:35.212929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.447107ms","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-08-20T20:32:35.213006Z","caller":"traceutil/trace.go:171","msg":"trace[134842396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"129.568548ms","start":"2026-08-20T20:32:35.083421Z","end":"2026-08-20T20:32:35.21299Z","steps":["trace[134842396] 'agreement among raft nodes before linearized reading' (duration: 129.334156ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:36.857626Z","caller":"traceutil/trace.go:171","msg":"trace[1944276311] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"115.422309ms","start":"2026-08-20T20:32:36.742186Z","end":"2026-08-20T20:32:36.857608Z","steps":["trace[1944276311] 'process raft request' (duration: 115.304238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:37.260266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.407958ms","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-08-20T20:32:37.260343Z","caller":"traceutil/trace.go:171","msg":"trace[863237992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1328; }","duration":"220.533779ms","start":"2026-08-20T20:32:37.03979Z","end":"2026-08-20T20:32:37.260324Z","steps":["trace[863237992] 'range keys from in-memory index tree' (duration: 220.230276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:37.26021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.395704ms","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-08-20T20:32:37.260591Z","caller":"traceutil/trace.go:171","msg":"trace[1478061891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"342.825328ms","start":"2026-08-20T20:32:36.917748Z","end":"2026-08-20T20:32:37.260573Z","steps":["trace[1478061891] 'range keys from in-memory index tree' (duration: 342.180042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:37.260649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:36.917732Z","time spent":"342.897708ms","remote":"127.0.0.1:54592","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-08-20T20:32:37.410311Z","caller":"traceutil/trace.go:171","msg":"trace[961661598] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"193.266537ms","start":"2026-08-20T20:32:37.217023Z","end":"2026-08-20T20:32:37.41029Z","steps":["trace[961661598] 'read index received' (duration: 193.123676ms)","trace[961661598] 'applied index is now lower than readState.Index' (duration: 141.871µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:37.410408Z","caller":"traceutil/trace.go:171","msg":"trace[2064666610] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"350.719586ms","start":"2026-08-20T20:32:37.059666Z","end":"2026-08-20T20:32:37.410386Z","steps":["trace[2064666610] 'process raft request' (duration: 350.470503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:37.410576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.49485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-20T20:32:37.410625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:37.05964Z","time spent":"350.817097ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:32:37.410658Z","caller":"traceutil/trace.go:171","msg":"trace[88287926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1329; }","duration":"193.671092ms","start":"2026-08-20T20:32:37.216969Z","end":"2026-08-20T20:32:37.41064Z","steps":["trace[88287926] 'agreement among raft nodes before linearized reading' (duration: 193.43421ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:37.486919Z","caller":"traceutil/trace.go:171","msg":"trace[1696995809] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"220.995862ms","start":"2026-08-20T20:32:37.265881Z","end":"2026-08-20T20:32:37.486877Z","steps":["trace[1696995809] 'process raft request' (duration: 220.875381ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:37.486929Z","caller":"traceutil/trace.go:171","msg":"trace[433717277] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"222.696938ms","start":"2026-08-20T20:32:37.264203Z","end":"2026-08-20T20:32:37.4869Z","steps":["trace[433717277] 'process raft request' (duration: 219.925683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:37.659549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.682347ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T20:32:37.660076Z","caller":"traceutil/trace.go:171","msg":"trace[1653422509] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1385; }","duration":"249.674206ms","start":"2026-08-20T20:32:37.410379Z","end":"2026-08-20T20:32:37.660053Z","steps":["trace[1653422509] 'read index received' (duration: 73.757158ms)","trace[1653422509] 'applied index is now lower than readState.Index' (duration: 175.915188ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:37.660399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.727659ms","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-08-20T20:32:37.660618Z","caller":"traceutil/trace.go:171","msg":"trace[211011486] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"247.158953ms","start":"2026-08-20T20:32:37.413435Z","end":"2026-08-20T20:32:37.660593Z","steps":["trace[211011486] 'process raft request' (duration: 246.559948ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:37.660531Z","caller":"traceutil/trace.go:171","msg":"trace[1089064344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1331; }","duration":"433.908781ms","start":"2026-08-20T20:32:37.226598Z","end":"2026-08-20T20:32:37.660507Z","steps":["trace[1089064344] 'agreement among raft nodes before linearized reading' (duration: 433.579068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:37.660767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:37.226585Z","time spent":"434.124263ms","remote":"127.0.0.1:54592","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-08-20T20:32:37.660781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.31058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:37.660861Z","caller":"traceutil/trace.go:171","msg":"trace[813678774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"227.426241ms","start":"2026-08-20T20:32:37.43342Z","end":"2026-08-20T20:32:37.660847Z","steps":["trace[813678774] 'agreement among raft nodes before linearized reading' (duration: 227.156909ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:39.079637Z","caller":"traceutil/trace.go:171","msg":"trace[27046683] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"213.536073ms","start":"2026-08-20T20:32:38.866078Z","end":"2026-08-20T20:32:39.079614Z","steps":["trace[27046683] 'process raft request' (duration: 213.380732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:41.992066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.674698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:32:41.992179Z","caller":"traceutil/trace.go:171","msg":"trace[309392787] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"209.839191ms","start":"2026-08-20T20:32:41.782322Z","end":"2026-08-20T20:32:41.992161Z","steps":["trace[309392787] 'process raft request' (duration: 71.957381ms)","trace[309392787] 'compare' (duration: 137.554287ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:43.838345Z","caller":"traceutil/trace.go:171","msg":"trace[973306291] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"190.634265ms","start":"2026-08-20T20:32:43.647683Z","end":"2026-08-20T20:32:43.838317Z","steps":["trace[973306291] 'process raft request' (duration: 190.385293ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:43.844472Z","caller":"traceutil/trace.go:171","msg":"trace[740441026] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"196.494898ms","start":"2026-08-20T20:32:43.647933Z","end":"2026-08-20T20:32:43.844427Z","steps":["trace[740441026] 'process raft request' (duration: 196.268496ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:45.961723Z","caller":"traceutil/trace.go:171","msg":"trace[875233389] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1418; }","duration":"113.205976ms","start":"2026-08-20T20:32:45.848493Z","end":"2026-08-20T20:32:45.961699Z","steps":["trace[875233389] 'read index received' (duration: 112.984933ms)","trace[875233389] 'applied index is now lower than readState.Index' (duration: 220.123µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:45.96177Z","caller":"traceutil/trace.go:171","msg":"trace[918504757] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"114.348459ms","start":"2026-08-20T20:32:45.847387Z","end":"2026-08-20T20:32:45.961735Z","steps":["trace[918504757] 'process raft request' (duration: 114.163837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:45.962023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.502529ms","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-08-20T20:32:45.962065Z","caller":"traceutil/trace.go:171","msg":"trace[2087736744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"113.57529ms","start":"2026-08-20T20:32:45.848474Z","end":"2026-08-20T20:32:45.96205Z","steps":["trace[2087736744] 'agreement among raft nodes before linearized reading' (duration: 113.406788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:46.179487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.571457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:32:46.179548Z","caller":"traceutil/trace.go:171","msg":"trace[1049693045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1365; }","duration":"111.652658ms","start":"2026-08-20T20:32:46.067884Z","end":"2026-08-20T20:32:46.179537Z","steps":["trace[1049693045] 'range keys from in-memory index tree' (duration: 111.405066ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:47.334603Z","caller":"traceutil/trace.go:171","msg":"trace[706892128] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"197.747639ms","start":"2026-08-20T20:32:47.136837Z","end":"2026-08-20T20:32:47.334585Z","steps":["trace[706892128] 'process raft request' (duration: 197.646848ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:33:00.177167Z","caller":"traceutil/trace.go:171","msg":"trace[169342161] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"138.373537ms","start":"2026-08-20T20:33:00.038768Z","end":"2026-08-20T20:33:00.177141Z","steps":["trace[169342161] 'process raft request' (duration: 138.179554ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:33:00.177373Z","caller":"traceutil/trace.go:171","msg":"trace[692299432] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"138.57416ms","start":"2026-08-20T20:33:00.038768Z","end":"2026-08-20T20:33:00.177342Z","steps":["trace[692299432] 'process raft request' (duration: 138.329926ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:33:00.224036Z","caller":"traceutil/trace.go:171","msg":"trace[1797974644] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"184.504652ms","start":"2026-08-20T20:33:00.039496Z","end":"2026-08-20T20:33:00.224Z","steps":["trace[1797974644] 'process raft request' (duration: 166.110155ms)","trace[1797974644] 'compare' (duration: 18.268895ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:33:25.801682Z","caller":"traceutil/trace.go:171","msg":"trace[439893863] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"294.608422ms","start":"2026-08-20T20:33:25.507046Z","end":"2026-08-20T20:33:25.801655Z","steps":["trace[439893863] 'process raft request' (duration: 294.251235ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:33:25.940137Z","caller":"traceutil/trace.go:171","msg":"trace[1798734158] linearizableReadLoop","detail":"{readStateIndex:1565; appliedIndex:1563; }","duration":"192.428051ms","start":"2026-08-20T20:33:25.747653Z","end":"2026-08-20T20:33:25.940081Z","steps":["trace[1798734158] 'read index received' (duration: 53.641043ms)","trace[1798734158] 'applied index is now lower than readState.Index' (duration: 138.785767ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:33:25.94028Z","caller":"traceutil/trace.go:171","msg":"trace[1909148327] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"287.227722ms","start":"2026-08-20T20:33:25.653021Z","end":"2026-08-20T20:33:25.940249Z","steps":["trace[1909148327] 'process raft request' (duration: 223.437612ms)","trace[1909148327] 'compare' (duration: 63.457383ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:33:25.940331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.655576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:33:25.940378Z","caller":"traceutil/trace.go:171","msg":"trace[990709967] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1498; }","duration":"192.763617ms","start":"2026-08-20T20:33:25.747596Z","end":"2026-08-20T20:33:25.940359Z","steps":["trace[990709967] 'agreement among raft nodes before linearized reading' (duration: 192.669305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:33:26.764929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.799839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:33:26.765035Z","caller":"traceutil/trace.go:171","msg":"trace[1064078718] range","detail":"{range_begin:/registry/pods/kube-node-lease/; range_end:/registry/pods/kube-node-lease0; response_count:0; response_revision:1503; }","duration":"110.968842ms","start":"2026-08-20T20:33:26.654045Z","end":"2026-08-20T20:33:26.765013Z","steps":["trace[1064078718] 'range keys from in-memory index tree' (duration: 110.737177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:33:26.765236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.765954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-rt2wf\" ","response":"range_response_count:1 size:5487"} {"level":"info","ts":"2026-08-20T20:33:26.765302Z","caller":"traceutil/trace.go:171","msg":"trace[1090156269] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-rt2wf; range_end:; response_count:1; response_revision:1503; }","duration":"101.868655ms","start":"2026-08-20T20:33:26.663417Z","end":"2026-08-20T20:33:26.765286Z","steps":["trace[1090156269] 'range keys from in-memory index tree' (duration: 101.589589ms)"],"step_count":1}