{"level":"warn","ts":"2026-07-09T16:40:02.695675Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:40:02.695747Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.169: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.169:2380","--initial-cluster=instance=https://199.19.213.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.169: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-07-09T16:40:02.695808Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:40:02.695817Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-07-09T16:40:02.695839Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T16:40:02.69649Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"]} {"level":"info","ts":"2026-07-09T16:40:02.696562Z","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.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-09T16:40:02.701454Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.705508ms"} {"level":"info","ts":"2026-07-09T16:40:02.715474Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a742f9f5aa7326e5","cluster-id":"a6b466adbadbbcd5"} {"level":"info","ts":"2026-07-09T16:40:02.715559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T16:40:02.71559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 0"} {"level":"info","ts":"2026-07-09T16:40:02.715604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a742f9f5aa7326e5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T16:40:02.715612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 1"} {"level":"info","ts":"2026-07-09T16:40:02.715647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"warn","ts":"2026-07-09T16:40:02.734915Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T16:40:02.739259Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T16:40:02.743712Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-09T16:40:02.746504Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a742f9f5aa7326e5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T16:40:02.747284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"info","ts":"2026-07-09T16:40:02.747379Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","added-peer-id":"a742f9f5aa7326e5","added-peer-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-07-09T16:40:02.747505Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a742f9f5aa7326e5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T16:40:02.747596Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T16:40:02.74762Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T16:40:02.747629Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T16:40:02.750137Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T16:40:02.750332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a742f9f5aa7326e5","initial-advertise-peer-urls":["https://199.19.213.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T16:40:02.750367Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T16:40:02.750456Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-07-09T16:40:02.75047Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-07-09T16:40:03.11852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T16:40:03.118564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T16:40:03.118584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgPreVoteResp from a742f9f5aa7326e5 at term 1"} {"level":"info","ts":"2026-07-09T16:40:03.118594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became candidate at term 2"} {"level":"info","ts":"2026-07-09T16:40:03.118601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgVoteResp from a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-07-09T16:40:03.118609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became leader at term 2"} {"level":"info","ts":"2026-07-09T16:40:03.118616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a742f9f5aa7326e5 elected leader a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-07-09T16:40:03.11963Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:40:03.1232Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a742f9f5aa7326e5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.169:2379]}","request-path":"/0/members/a742f9f5aa7326e5/attributes","cluster-id":"a6b466adbadbbcd5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T16:40:03.123414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:40:03.123545Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T16:40:03.123617Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T16:40:03.123389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:40:03.124969Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:40:03.125101Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:40:03.125132Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:40:03.127543Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T16:40:03.128118Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.169:2379"} {"level":"info","ts":"2026-07-09T16:40:35.357536Z","caller":"traceutil/trace.go:171","msg":"trace[1830456430] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"106.538144ms","start":"2026-07-09T16:40:35.250966Z","end":"2026-07-09T16:40:35.357504Z","steps":["trace[1830456430] 'process raft request' (duration: 106.239133ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:39.840495Z","caller":"traceutil/trace.go:171","msg":"trace[2125670136] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"466.095137ms","start":"2026-07-09T16:40:39.374362Z","end":"2026-07-09T16:40:39.840457Z","steps":["trace[2125670136] 'read index received' (duration: 465.972823ms)","trace[2125670136] 'applied index is now lower than readState.Index' (duration: 121.174µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:39.840635Z","caller":"traceutil/trace.go:171","msg":"trace[1816172778] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"466.650726ms","start":"2026-07-09T16:40:39.373925Z","end":"2026-07-09T16:40:39.840575Z","steps":["trace[1816172778] 'process raft request' (duration: 466.335626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.840842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.419868ms","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-07-09T16:40:39.840922Z","caller":"traceutil/trace.go:171","msg":"trace[1812178657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:485; }","duration":"466.569844ms","start":"2026-07-09T16:40:39.374339Z","end":"2026-07-09T16:40:39.840909Z","steps":["trace[1812178657] 'agreement among raft nodes before linearized reading' (duration: 466.303894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.840973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.374332Z","time spent":"466.625016ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:40:39.842471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.373903Z","time spent":"466.968567ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:40:39.849102Z","caller":"traceutil/trace.go:171","msg":"trace[1889586063] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"366.076753ms","start":"2026-07-09T16:40:39.48301Z","end":"2026-07-09T16:40:39.849086Z","steps":["trace[1889586063] 'process raft request' (duration: 365.755292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.84924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.482992Z","time spent":"366.144896ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:39.849236Z","caller":"traceutil/trace.go:171","msg":"trace[932680504] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"380.462321ms","start":"2026-07-09T16:40:39.46866Z","end":"2026-07-09T16:40:39.849122Z","steps":["trace[932680504] 'process raft request' (duration: 379.991436ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:39.849291Z","caller":"traceutil/trace.go:171","msg":"trace[1702878501] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"366.174726ms","start":"2026-07-09T16:40:39.483064Z","end":"2026-07-09T16:40:39.849239Z","steps":["trace[1702878501] 'process raft request' (duration: 365.747482ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:39.849361Z","caller":"traceutil/trace.go:171","msg":"trace[1944116509] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"375.155691ms","start":"2026-07-09T16:40:39.474197Z","end":"2026-07-09T16:40:39.849352Z","steps":["trace[1944116509] 'process raft request' (duration: 374.513779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.849407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.48306Z","time spent":"366.296ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:40:39.849437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.474177Z","time spent":"375.225854ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:39.849441Z","caller":"traceutil/trace.go:171","msg":"trace[1891653687] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"366.176526ms","start":"2026-07-09T16:40:39.483224Z","end":"2026-07-09T16:40:39.849401Z","steps":["trace[1891653687] 'process raft request' (duration: 365.656968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.849514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.468644Z","time spent":"380.69684ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:39.849537Z","caller":"traceutil/trace.go:171","msg":"trace[1125056117] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"364.581233ms","start":"2026-07-09T16:40:39.484918Z","end":"2026-07-09T16:40:39.849499Z","steps":["trace[1125056117] 'process raft request' (duration: 364.017414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.849582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.483219Z","time spent":"366.28691ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:39.849614Z","caller":"traceutil/trace.go:171","msg":"trace[2022237901] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"349.022774ms","start":"2026-07-09T16:40:39.500583Z","end":"2026-07-09T16:40:39.849606Z","steps":["trace[2022237901] 'process raft request' (duration: 348.467584ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:39.849532Z","caller":"traceutil/trace.go:171","msg":"trace[1687246571] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"361.169157ms","start":"2026-07-09T16:40:39.48835Z","end":"2026-07-09T16:40:39.849519Z","steps":["trace[1687246571] 'process raft request' (duration: 360.639709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.849648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.484892Z","time spent":"364.711587ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:40:39.849694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.500562Z","time spent":"349.079626ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:39.849548Z","caller":"traceutil/trace.go:171","msg":"trace[938634497] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"384.368263ms","start":"2026-07-09T16:40:39.465172Z","end":"2026-07-09T16:40:39.84954Z","steps":["trace[938634497] 'process raft request' (duration: 383.312168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.849713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.488332Z","time spent":"361.331111ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:40:39.849777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.465151Z","time spent":"384.600202ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:39.850055Z","caller":"traceutil/trace.go:171","msg":"trace[2131439176] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"349.360666ms","start":"2026-07-09T16:40:39.500677Z","end":"2026-07-09T16:40:39.850038Z","steps":["trace[2131439176] 'process raft request' (duration: 348.437994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.85014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.500644Z","time spent":"349.455389ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:39.850421Z","caller":"traceutil/trace.go:171","msg":"trace[371755272] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"322.752033ms","start":"2026-07-09T16:40:39.527658Z","end":"2026-07-09T16:40:39.85041Z","steps":["trace[371755272] 'process raft request' (duration: 322.334659ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:39.850434Z","caller":"traceutil/trace.go:171","msg":"trace[1255440344] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"188.808347ms","start":"2026-07-09T16:40:39.661616Z","end":"2026-07-09T16:40:39.850424Z","steps":["trace[1255440344] 'process raft request' (duration: 188.642212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:39.850469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:39.527635Z","time spent":"322.814885ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:40:39.850638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.152858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:39.850698Z","caller":"traceutil/trace.go:171","msg":"trace[2121845104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"110.281322ms","start":"2026-07-09T16:40:39.740385Z","end":"2026-07-09T16:40:39.850666Z","steps":["trace[2121845104] 'agreement among raft nodes before linearized reading' (duration: 110.18873ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:40.236574Z","caller":"traceutil/trace.go:171","msg":"trace[2005980988] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"164.575936ms","start":"2026-07-09T16:40:40.071981Z","end":"2026-07-09T16:40:40.236557Z","steps":["trace[2005980988] 'process raft request' (duration: 98.994336ms)","trace[2005980988] 'compare' (duration: 65.259209ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:40.236736Z","caller":"traceutil/trace.go:171","msg":"trace[684078398] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"157.389742ms","start":"2026-07-09T16:40:40.079322Z","end":"2026-07-09T16:40:40.236712Z","steps":["trace[684078398] 'process raft request' (duration: 157.01532ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:40.263183Z","caller":"traceutil/trace.go:171","msg":"trace[942466870] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"148.576477ms","start":"2026-07-09T16:40:40.114586Z","end":"2026-07-09T16:40:40.263162Z","steps":["trace[942466870] 'process raft request' (duration: 148.36913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:41.619408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.778404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:40:41.619635Z","caller":"traceutil/trace.go:171","msg":"trace[1971270237] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.366242489s","start":"2026-07-09T16:40:40.253372Z","end":"2026-07-09T16:40:41.619614Z","steps":["trace[1971270237] 'process raft request' (duration: 560.099623ms)","trace[1971270237] 'compare' (duration: 805.539445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:41.6197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:40.253346Z","time spent":"1.366326201s","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:40:41.619795Z","caller":"traceutil/trace.go:171","msg":"trace[1821229272] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.302864524s","start":"2026-07-09T16:40:40.316923Z","end":"2026-07-09T16:40:41.619788Z","steps":["trace[1821229272] 'process raft request' (duration: 1.302708778s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:41.619809Z","caller":"traceutil/trace.go:171","msg":"trace[1956609400] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.330280978s","start":"2026-07-09T16:40:40.289515Z","end":"2026-07-09T16:40:41.619796Z","steps":["trace[1956609400] 'process raft request' (duration: 1.329997858s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:41.619834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:40.316897Z","time spent":"1.302918374s","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:40:41.619856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:40.289489Z","time spent":"1.330342819s","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:40:41.619893Z","caller":"traceutil/trace.go:171","msg":"trace[1001216130] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:561; }","duration":"1.13212477s","start":"2026-07-09T16:40:40.487761Z","end":"2026-07-09T16:40:41.619886Z","steps":["trace[1001216130] 'read index received' (duration: 325.746987ms)","trace[1001216130] 'applied index is now lower than readState.Index' (duration: 806.376433ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:41.63293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02153464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:40:41.633022Z","caller":"traceutil/trace.go:171","msg":"trace[1799849623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"1.021671385s","start":"2026-07-09T16:40:40.611334Z","end":"2026-07-09T16:40:41.633006Z","steps":["trace[1799849623] 'agreement among raft nodes before linearized reading' (duration: 1.02151307s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:41.633065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:40.611313Z","time spent":"1.021741589s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:40:41.633301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14554812s","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-07-09T16:40:41.633339Z","caller":"traceutil/trace.go:171","msg":"trace[620752986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"1.145619231s","start":"2026-07-09T16:40:40.48771Z","end":"2026-07-09T16:40:41.633329Z","steps":["trace[620752986] 'agreement among raft nodes before linearized reading' (duration: 1.132220173s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:41.633366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:40.487691Z","time spent":"1.145668743s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:40:41.633516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.948688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:41.633552Z","caller":"traceutil/trace.go:171","msg":"trace[2080032305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"893.011891ms","start":"2026-07-09T16:40:40.740531Z","end":"2026-07-09T16:40:41.633543Z","steps":["trace[2080032305] 'agreement among raft nodes before linearized reading' (duration: 892.961529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:41.633579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:40.740513Z","time spent":"893.060772ms","remote":"127.0.0.1:59976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:40:46.504204Z","caller":"traceutil/trace.go:171","msg":"trace[129291862] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"301.965461ms","start":"2026-07-09T16:40:46.202203Z","end":"2026-07-09T16:40:46.504168Z","steps":["trace[129291862] 'read index received' (duration: 301.132013ms)","trace[129291862] 'applied index is now lower than readState.Index' (duration: 832.198µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:46.504361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.133606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T16:40:46.504292Z","caller":"traceutil/trace.go:171","msg":"trace[848671032] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"348.593918ms","start":"2026-07-09T16:40:46.155645Z","end":"2026-07-09T16:40:46.504239Z","steps":["trace[848671032] 'process raft request' (duration: 347.897695ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:46.504461Z","caller":"traceutil/trace.go:171","msg":"trace[2140033882] range","detail":"{range_begin:/registry/masterleases/199.19.213.169; range_end:; response_count:1; response_revision:589; }","duration":"302.289281ms","start":"2026-07-09T16:40:46.202155Z","end":"2026-07-09T16:40:46.504445Z","steps":["trace[2140033882] 'agreement among raft nodes before linearized reading' (duration: 302.131755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:46.504514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:46.202134Z","time spent":"302.366963ms","remote":"127.0.0.1:59990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.169\" "} {"level":"warn","ts":"2026-07-09T16:40:46.504658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:46.155617Z","time spent":"348.876106ms","remote":"127.0.0.1:60054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:40:47.621752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.102286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:40:47.621852Z","caller":"traceutil/trace.go:171","msg":"trace[1442860725] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"882.019246ms","start":"2026-07-09T16:40:46.739815Z","end":"2026-07-09T16:40:47.621835Z","steps":["trace[1442860725] 'read index received' (duration: 481.63589ms)","trace[1442860725] 'applied index is now lower than readState.Index' (duration: 400.381876ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:47.621955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.14662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:47.621982Z","caller":"traceutil/trace.go:171","msg":"trace[92586727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"882.215653ms","start":"2026-07-09T16:40:46.739757Z","end":"2026-07-09T16:40:47.621973Z","steps":["trace[92586727] 'agreement among raft nodes before linearized reading' (duration: 882.12233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:47.622015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:46.739725Z","time spent":"882.282935ms","remote":"127.0.0.1:59976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:40:47.62217Z","caller":"traceutil/trace.go:171","msg":"trace[1525090639] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.06649377s","start":"2026-07-09T16:40:46.555646Z","end":"2026-07-09T16:40:47.62214Z","steps":["trace[1525090639] 'process raft request' (duration: 666.107884ms)","trace[1525090639] 'compare' (duration: 399.706534ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:47.622312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:46.555434Z","time spent":"1.066795819s","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:48.109951Z","caller":"traceutil/trace.go:171","msg":"trace[1645997827] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"120.460155ms","start":"2026-07-09T16:40:47.989461Z","end":"2026-07-09T16:40:48.109921Z","steps":["trace[1645997827] 'process raft request' (duration: 104.224388ms)","trace[1645997827] 'compare' (duration: 16.105093ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:48.125077Z","caller":"traceutil/trace.go:171","msg":"trace[1253316745] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"125.360599ms","start":"2026-07-09T16:40:47.999689Z","end":"2026-07-09T16:40:48.12505Z","steps":["trace[1253316745] 'process raft request' (duration: 125.146952ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:48.448371Z","caller":"traceutil/trace.go:171","msg":"trace[771330428] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"109.166392ms","start":"2026-07-09T16:40:48.339186Z","end":"2026-07-09T16:40:48.448353Z","steps":["trace[771330428] 'process raft request' (duration: 109.078919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:49.148341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.476465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:40:49.148642Z","caller":"traceutil/trace.go:171","msg":"trace[1437116433] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"194.533234ms","start":"2026-07-09T16:40:48.954088Z","end":"2026-07-09T16:40:49.148621Z","steps":["trace[1437116433] 'process raft request' (duration: 194.430531ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:49.148809Z","caller":"traceutil/trace.go:171","msg":"trace[384272748] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"559.604027ms","start":"2026-07-09T16:40:48.589188Z","end":"2026-07-09T16:40:49.148792Z","steps":["trace[384272748] 'process raft request' (duration: 422.576055ms)","trace[384272748] 'compare' (duration: 136.095102ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:49.148792Z","caller":"traceutil/trace.go:171","msg":"trace[1129231286] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"409.546771ms","start":"2026-07-09T16:40:48.739205Z","end":"2026-07-09T16:40:49.148752Z","steps":["trace[1129231286] 'read index received' (duration: 272.414827ms)","trace[1129231286] 'applied index is now lower than readState.Index' (duration: 137.120944ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:49.148885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:48.589163Z","time spent":"559.67846ms","remote":"127.0.0.1:60054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:40:49.149019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.802021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:49.149075Z","caller":"traceutil/trace.go:171","msg":"trace[1243562135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"409.896943ms","start":"2026-07-09T16:40:48.739162Z","end":"2026-07-09T16:40:49.149059Z","steps":["trace[1243562135] 'agreement among raft nodes before linearized reading' (duration: 409.737429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:49.149119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:48.739141Z","time spent":"409.967055ms","remote":"127.0.0.1:59970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:40:49.149471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.681124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9xstx\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-07-09T16:40:49.14955Z","caller":"traceutil/trace.go:171","msg":"trace[215145929] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9xstx; range_end:; response_count:1; response_revision:603; }","duration":"154.768035ms","start":"2026-07-09T16:40:48.994752Z","end":"2026-07-09T16:40:49.14952Z","steps":["trace[215145929] 'agreement among raft nodes before linearized reading' (duration: 154.346193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:49.440159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.265292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:40:49.440648Z","caller":"traceutil/trace.go:171","msg":"trace[1812578069] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"275.102166ms","start":"2026-07-09T16:40:49.165169Z","end":"2026-07-09T16:40:49.440272Z","steps":["trace[1812578069] 'process raft request' (duration: 153.620457ms)","trace[1812578069] 'compare' (duration: 121.123128ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:49.464146Z","caller":"traceutil/trace.go:171","msg":"trace[1027723288] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"266.073131ms","start":"2026-07-09T16:40:49.198042Z","end":"2026-07-09T16:40:49.464115Z","steps":["trace[1027723288] 'process raft request' (duration: 265.940766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:50.405211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.192972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:40:50.405507Z","caller":"traceutil/trace.go:171","msg":"trace[528422405] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"310.182725ms","start":"2026-07-09T16:40:50.095287Z","end":"2026-07-09T16:40:50.40547Z","steps":["trace[528422405] 'process raft request' (duration: 122.652931ms)","trace[528422405] 'compare' (duration: 187.093089ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:50.405626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:50.095263Z","time spent":"310.314289ms","remote":"127.0.0.1:60154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:40:50.40567Z","caller":"traceutil/trace.go:171","msg":"trace[840527930] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"289.986088ms","start":"2026-07-09T16:40:50.115653Z","end":"2026-07-09T16:40:50.405639Z","steps":["trace[840527930] 'read index received' (duration: 102.31046ms)","trace[840527930] 'applied index is now lower than readState.Index' (duration: 187.673138ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:50.405745Z","caller":"traceutil/trace.go:171","msg":"trace[956359491] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"197.094494ms","start":"2026-07-09T16:40:50.208529Z","end":"2026-07-09T16:40:50.405624Z","steps":["trace[956359491] 'process raft request' (duration: 196.840126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:50.405815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.149514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-09T16:40:50.405865Z","caller":"traceutil/trace.go:171","msg":"trace[1433544391] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:614; }","duration":"290.226686ms","start":"2026-07-09T16:40:50.115621Z","end":"2026-07-09T16:40:50.405848Z","steps":["trace[1433544391] 'agreement among raft nodes before linearized reading' (duration: 290.108352ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:50.710045Z","caller":"traceutil/trace.go:171","msg":"trace[1713365596] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"292.813716ms","start":"2026-07-09T16:40:50.417212Z","end":"2026-07-09T16:40:50.710026Z","steps":["trace[1713365596] 'process raft request' (duration: 292.702803ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:50.867201Z","caller":"traceutil/trace.go:171","msg":"trace[180664100] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"151.116578ms","start":"2026-07-09T16:40:50.716054Z","end":"2026-07-09T16:40:50.86717Z","steps":["trace[180664100] 'process raft request' (duration: 151.069006ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:50.867278Z","caller":"traceutil/trace.go:171","msg":"trace[1021650641] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"151.303663ms","start":"2026-07-09T16:40:50.71594Z","end":"2026-07-09T16:40:50.867244Z","steps":["trace[1021650641] 'process raft request' (duration: 151.115048ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:50.867192Z","caller":"traceutil/trace.go:171","msg":"trace[1258413808] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"151.943983ms","start":"2026-07-09T16:40:50.715205Z","end":"2026-07-09T16:40:50.867149Z","steps":["trace[1258413808] 'process raft request' (duration: 151.739177ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:51.283672Z","caller":"traceutil/trace.go:171","msg":"trace[459582123] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"147.482523ms","start":"2026-07-09T16:40:51.136157Z","end":"2026-07-09T16:40:51.283639Z","steps":["trace[459582123] 'read index received' (duration: 145.784929ms)","trace[459582123] 'applied index is now lower than readState.Index' (duration: 1.696064ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:51.283829Z","caller":"traceutil/trace.go:171","msg":"trace[298756240] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"168.299374ms","start":"2026-07-09T16:40:51.115498Z","end":"2026-07-09T16:40:51.283797Z","steps":["trace[298756240] 'process raft request' (duration: 166.507099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:51.284019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.846884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-09T16:40:51.284065Z","caller":"traceutil/trace.go:171","msg":"trace[809836238] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:622; }","duration":"147.941147ms","start":"2026-07-09T16:40:51.136112Z","end":"2026-07-09T16:40:51.284053Z","steps":["trace[809836238] 'agreement among raft nodes before linearized reading' (duration: 147.619727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:51.915249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.000818ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T16:40:51.915616Z","caller":"traceutil/trace.go:171","msg":"trace[1900600971] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"616.847773ms","start":"2026-07-09T16:40:51.298728Z","end":"2026-07-09T16:40:51.915576Z","steps":["trace[1900600971] 'read index received' (duration: 100.518994ms)","trace[1900600971] 'applied index is now lower than readState.Index' (duration: 516.321779ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:51.91573Z","caller":"traceutil/trace.go:171","msg":"trace[1538095182] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"511.855898ms","start":"2026-07-09T16:40:51.403829Z","end":"2026-07-09T16:40:51.915685Z","steps":["trace[1538095182] 'process raft request' (duration: 511.555229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:51.915852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:51.403811Z","time spent":"511.986103ms","remote":"127.0.0.1:60040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:40:51.962736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.754564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T16:40:51.962764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.013807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:51.962854Z","caller":"traceutil/trace.go:171","msg":"trace[966095536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"157.903978ms","start":"2026-07-09T16:40:51.804923Z","end":"2026-07-09T16:40:51.962827Z","steps":["trace[966095536] 'agreement among raft nodes before linearized reading' (duration: 110.972941ms)","trace[966095536] 'range keys from in-memory index tree' (duration: 46.757752ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:51.962862Z","caller":"traceutil/trace.go:171","msg":"trace[1705378067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:623; }","duration":"664.14645ms","start":"2026-07-09T16:40:51.29869Z","end":"2026-07-09T16:40:51.962837Z","steps":["trace[1705378067] 'agreement among raft nodes before linearized reading' (duration: 617.145141ms)","trace[1705378067] 'range keys from in-memory index tree' (duration: 46.866025ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:51.962848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.265762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:40:51.96294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:51.298677Z","time spent":"664.249735ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T16:40:51.962966Z","caller":"traceutil/trace.go:171","msg":"trace[373028341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"223.430517ms","start":"2026-07-09T16:40:51.739505Z","end":"2026-07-09T16:40:51.962936Z","steps":["trace[373028341] 'agreement among raft nodes before linearized reading' (duration: 176.363155ms)","trace[373028341] 'range keys from in-memory index tree' (duration: 46.861146ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:51.962735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.452149ms","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-07-09T16:40:51.963078Z","caller":"traceutil/trace.go:171","msg":"trace[391708037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"208.833691ms","start":"2026-07-09T16:40:51.754215Z","end":"2026-07-09T16:40:51.963049Z","steps":["trace[391708037] 'agreement among raft nodes before linearized reading' (duration: 161.703547ms)","trace[391708037] 'range keys from in-memory index tree' (duration: 46.725421ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:52.177688Z","caller":"traceutil/trace.go:171","msg":"trace[628105940] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"116.105738ms","start":"2026-07-09T16:40:52.061548Z","end":"2026-07-09T16:40:52.177654Z","steps":["trace[628105940] 'read index received' (duration: 17.124292ms)","trace[628105940] 'applied index is now lower than readState.Index' (duration: 98.979966ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:52.177918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.359255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-8cgf7\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-07-09T16:40:52.177989Z","caller":"traceutil/trace.go:171","msg":"trace[461196741] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-8cgf7; range_end:; response_count:1; response_revision:627; }","duration":"116.474569ms","start":"2026-07-09T16:40:52.061503Z","end":"2026-07-09T16:40:52.177977Z","steps":["trace[461196741] 'agreement among raft nodes before linearized reading' (duration: 116.317235ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:52.178333Z","caller":"traceutil/trace.go:171","msg":"trace[1583085155] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"118.061069ms","start":"2026-07-09T16:40:52.05972Z","end":"2026-07-09T16:40:52.177781Z","steps":["trace[1583085155] 'process raft request' (duration: 18.943519ms)","trace[1583085155] 'compare' (duration: 98.841902ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:52.209989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.905655ms","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-07-09T16:40:52.210048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.898361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:52.210063Z","caller":"traceutil/trace.go:171","msg":"trace[1413074589] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"119.020109ms","start":"2026-07-09T16:40:52.091024Z","end":"2026-07-09T16:40:52.210044Z","steps":["trace[1413074589] 'agreement among raft nodes before linearized reading' (duration: 118.878235ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:52.210093Z","caller":"traceutil/trace.go:171","msg":"trace[1336889098] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"142.947322ms","start":"2026-07-09T16:40:52.067129Z","end":"2026-07-09T16:40:52.210076Z","steps":["trace[1336889098] 'agreement among raft nodes before linearized reading' (duration: 142.88604ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:52.210144Z","caller":"traceutil/trace.go:171","msg":"trace[1753385994] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"144.068846ms","start":"2026-07-09T16:40:52.06604Z","end":"2026-07-09T16:40:52.210108Z","steps":["trace[1753385994] 'process raft request' (duration: 143.684705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:52.210166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.4389ms","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":"warn","ts":"2026-07-09T16:40:52.210215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.359586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:52.210248Z","caller":"traceutil/trace.go:171","msg":"trace[1730676530] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"145.399698ms","start":"2026-07-09T16:40:52.064839Z","end":"2026-07-09T16:40:52.210239Z","steps":["trace[1730676530] 'agreement among raft nodes before linearized reading' (duration: 145.357116ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:52.210267Z","caller":"traceutil/trace.go:171","msg":"trace[301854104] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"144.550963ms","start":"2026-07-09T16:40:52.065685Z","end":"2026-07-09T16:40:52.210235Z","steps":["trace[301854104] 'agreement among raft nodes before linearized reading' (duration: 144.365787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:52.210134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.231087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:52.210351Z","caller":"traceutil/trace.go:171","msg":"trace[186495621] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"139.490095ms","start":"2026-07-09T16:40:52.070825Z","end":"2026-07-09T16:40:52.210315Z","steps":["trace[186495621] 'agreement among raft nodes before linearized reading' (duration: 139.155414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:52.210397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.221871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:52.210439Z","caller":"traceutil/trace.go:171","msg":"trace[1217315159] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"144.268432ms","start":"2026-07-09T16:40:52.066163Z","end":"2026-07-09T16:40:52.210431Z","steps":["trace[1217315159] 'agreement among raft nodes before linearized reading' (duration: 144.218041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:52.209978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.392242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:52.210473Z","caller":"traceutil/trace.go:171","msg":"trace[217866097] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"148.957839ms","start":"2026-07-09T16:40:52.061503Z","end":"2026-07-09T16:40:52.210461Z","steps":["trace[217866097] 'agreement among raft nodes before linearized reading' (duration: 148.38391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:52.21055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.80534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:52.210583Z","caller":"traceutil/trace.go:171","msg":"trace[760567981] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"144.860521ms","start":"2026-07-09T16:40:52.065715Z","end":"2026-07-09T16:40:52.210575Z","steps":["trace[760567981] 'agreement among raft nodes before linearized reading' (duration: 144.814699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:52.210646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.437433ms","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-07-09T16:40:52.210766Z","caller":"traceutil/trace.go:171","msg":"trace[1389187462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:628; }","duration":"148.542166ms","start":"2026-07-09T16:40:52.062142Z","end":"2026-07-09T16:40:52.210684Z","steps":["trace[1389187462] 'agreement among raft nodes before linearized reading' (duration: 148.37561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:52.210593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.550451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:52.210815Z","caller":"traceutil/trace.go:171","msg":"trace[568743424] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"143.774078ms","start":"2026-07-09T16:40:52.067025Z","end":"2026-07-09T16:40:52.210799Z","steps":["trace[568743424] 'agreement among raft nodes before linearized reading' (duration: 143.540811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:52.211658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.339494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:52.211743Z","caller":"traceutil/trace.go:171","msg":"trace[448815016] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"144.886994ms","start":"2026-07-09T16:40:52.066843Z","end":"2026-07-09T16:40:52.211729Z","steps":["trace[448815016] 'agreement among raft nodes before linearized reading' (duration: 143.190371ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:52.597798Z","caller":"traceutil/trace.go:171","msg":"trace[100425616] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"134.598123ms","start":"2026-07-09T16:40:52.463172Z","end":"2026-07-09T16:40:52.59777Z","steps":["trace[100425616] 'process raft request' (duration: 119.868335ms)","trace[100425616] 'compare' (duration: 14.605174ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:52.696355Z","caller":"traceutil/trace.go:171","msg":"trace[594477704] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"141.832227ms","start":"2026-07-09T16:40:52.554499Z","end":"2026-07-09T16:40:52.696332Z","steps":["trace[594477704] 'process raft request' (duration: 141.788326ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:52.696429Z","caller":"traceutil/trace.go:171","msg":"trace[1113367799] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"220.545774ms","start":"2026-07-09T16:40:52.475862Z","end":"2026-07-09T16:40:52.696407Z","steps":["trace[1113367799] 'process raft request' (duration: 220.298557ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:52.745182Z","caller":"traceutil/trace.go:171","msg":"trace[1832495455] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"142.431566ms","start":"2026-07-09T16:40:52.602725Z","end":"2026-07-09T16:40:52.745157Z","steps":["trace[1832495455] 'process raft request' (duration: 142.131727ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:52.745256Z","caller":"traceutil/trace.go:171","msg":"trace[1984987111] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"141.776616ms","start":"2026-07-09T16:40:52.603461Z","end":"2026-07-09T16:40:52.745237Z","steps":["trace[1984987111] 'process raft request' (duration: 141.566789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:52.999573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.673277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:40:52.999651Z","caller":"traceutil/trace.go:171","msg":"trace[892058486] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"250.05293ms","start":"2026-07-09T16:40:52.749584Z","end":"2026-07-09T16:40:52.999637Z","steps":["trace[892058486] 'process raft request' (duration: 141.265149ms)","trace[892058486] 'compare' (duration: 108.528083ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:53.171978Z","caller":"traceutil/trace.go:171","msg":"trace[140688782] transaction","detail":"{read_only:false; number_of_response:0; response_revision:641; }","duration":"422.333442ms","start":"2026-07-09T16:40:52.749613Z","end":"2026-07-09T16:40:53.171946Z","steps":["trace[140688782] 'process raft request' (duration: 388.619501ms)","trace[140688782] 'compare' (duration: 33.520395ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:53.172276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:52.749586Z","time spent":"422.477526ms","remote":"127.0.0.1:60462","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:53.17256Z","caller":"traceutil/trace.go:171","msg":"trace[571577459] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"422.192748ms","start":"2026-07-09T16:40:52.74988Z","end":"2026-07-09T16:40:53.172073Z","steps":["trace[571577459] 'process raft request' (duration: 421.952501ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.172557Z","caller":"traceutil/trace.go:171","msg":"trace[1992887329] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"421.484186ms","start":"2026-07-09T16:40:52.751031Z","end":"2026-07-09T16:40:53.172515Z","steps":["trace[1992887329] 'process raft request' (duration: 420.892537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:53.172734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:52.749859Z","time spent":"422.754265ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:40:53.173365Z","caller":"traceutil/trace.go:171","msg":"trace[125939696] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"309.513076ms","start":"2026-07-09T16:40:52.863838Z","end":"2026-07-09T16:40:53.173351Z","steps":["trace[125939696] 'process raft request' (duration: 309.470614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:53.173413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:52.751021Z","time spent":"422.271709ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:40:53.173475Z","caller":"traceutil/trace.go:171","msg":"trace[441442621] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"414.211119ms","start":"2026-07-09T16:40:52.759256Z","end":"2026-07-09T16:40:53.173467Z","steps":["trace[441442621] 'process raft request' (duration: 412.720273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:53.173523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:52.759245Z","time spent":"414.260821ms","remote":"127.0.0.1:60040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:40:53.173442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:52.86381Z","time spent":"309.600068ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:40:53.23145Z","caller":"traceutil/trace.go:171","msg":"trace[841429247] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"168.524574ms","start":"2026-07-09T16:40:53.0629Z","end":"2026-07-09T16:40:53.231424Z","steps":["trace[841429247] 'process raft request' (duration: 168.38796ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.313402Z","caller":"traceutil/trace.go:171","msg":"trace[683291819] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"131.46755ms","start":"2026-07-09T16:40:53.181891Z","end":"2026-07-09T16:40:53.313359Z","steps":["trace[683291819] 'process raft request' (duration: 114.718772ms)","trace[683291819] 'compare' (duration: 16.439368ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:53.313468Z","caller":"traceutil/trace.go:171","msg":"trace[807835254] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"130.449508ms","start":"2026-07-09T16:40:53.182987Z","end":"2026-07-09T16:40:53.313437Z","steps":["trace[807835254] 'process raft request' (duration: 130.220201ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.313565Z","caller":"traceutil/trace.go:171","msg":"trace[1005184354] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"123.861204ms","start":"2026-07-09T16:40:53.189667Z","end":"2026-07-09T16:40:53.313528Z","steps":["trace[1005184354] 'process raft request' (duration: 123.662178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:53.31418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.829277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9xstx\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-07-09T16:40:53.314241Z","caller":"traceutil/trace.go:171","msg":"trace[1540117542] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9xstx; range_end:; response_count:1; response_revision:649; }","duration":"127.932201ms","start":"2026-07-09T16:40:53.186295Z","end":"2026-07-09T16:40:53.314227Z","steps":["trace[1540117542] 'agreement among raft nodes before linearized reading' (duration: 127.718354ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.395913Z","caller":"traceutil/trace.go:171","msg":"trace[398304433] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"130.644084ms","start":"2026-07-09T16:40:53.265238Z","end":"2026-07-09T16:40:53.395882Z","steps":["trace[398304433] 'process raft request' (duration: 130.51452ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.517769Z","caller":"traceutil/trace.go:171","msg":"trace[1186248332] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"199.099177ms","start":"2026-07-09T16:40:53.318643Z","end":"2026-07-09T16:40:53.517742Z","steps":["trace[1186248332] 'process raft request' (duration: 143.883913ms)","trace[1186248332] 'compare' (duration: 54.803112ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:53.517966Z","caller":"traceutil/trace.go:171","msg":"trace[918923848] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"198.628613ms","start":"2026-07-09T16:40:53.319322Z","end":"2026-07-09T16:40:53.51795Z","steps":["trace[918923848] 'process raft request' (duration: 198.321494ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.518122Z","caller":"traceutil/trace.go:171","msg":"trace[1350459986] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"187.774119ms","start":"2026-07-09T16:40:53.330325Z","end":"2026-07-09T16:40:53.518099Z","steps":["trace[1350459986] 'process raft request' (duration: 187.614463ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.656437Z","caller":"traceutil/trace.go:171","msg":"trace[652457270] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"133.545044ms","start":"2026-07-09T16:40:53.522839Z","end":"2026-07-09T16:40:53.656384Z","steps":["trace[652457270] 'process raft request' (duration: 124.388661ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.656945Z","caller":"traceutil/trace.go:171","msg":"trace[1383933485] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"133.411749ms","start":"2026-07-09T16:40:53.523221Z","end":"2026-07-09T16:40:53.656633Z","steps":["trace[1383933485] 'process raft request' (duration: 133.10881ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.87094Z","caller":"traceutil/trace.go:171","msg":"trace[1383157867] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:690; }","duration":"129.594402ms","start":"2026-07-09T16:40:53.741334Z","end":"2026-07-09T16:40:53.870929Z","steps":["trace[1383157867] 'read index received' (duration: 62.81884ms)","trace[1383157867] 'applied index is now lower than readState.Index' (duration: 66.774782ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:53.871006Z","caller":"traceutil/trace.go:171","msg":"trace[468192878] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"203.853054ms","start":"2026-07-09T16:40:53.667132Z","end":"2026-07-09T16:40:53.870985Z","steps":["trace[468192878] 'process raft request' (duration: 203.73629ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.871043Z","caller":"traceutil/trace.go:171","msg":"trace[943399524] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"150.213199ms","start":"2026-07-09T16:40:53.720817Z","end":"2026-07-09T16:40:53.87103Z","steps":["trace[943399524] 'process raft request' (duration: 150.079755ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:53.871054Z","caller":"traceutil/trace.go:171","msg":"trace[400666855] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"204.548097ms","start":"2026-07-09T16:40:53.666462Z","end":"2026-07-09T16:40:53.87101Z","steps":["trace[400666855] 'process raft request' (duration: 137.831706ms)","trace[400666855] 'compare' (duration: 66.477662ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:53.871102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.753356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:53.871164Z","caller":"traceutil/trace.go:171","msg":"trace[1265522968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"129.860701ms","start":"2026-07-09T16:40:53.741294Z","end":"2026-07-09T16:40:53.871155Z","steps":["trace[1265522968] 'agreement among raft nodes before linearized reading' (duration: 129.746087ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.042197Z","caller":"traceutil/trace.go:171","msg":"trace[1254945182] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"159.875619ms","start":"2026-07-09T16:40:53.88231Z","end":"2026-07-09T16:40:54.042185Z","steps":["trace[1254945182] 'process raft request' (duration: 159.545559ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.042396Z","caller":"traceutil/trace.go:171","msg":"trace[2020464339] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"116.734638ms","start":"2026-07-09T16:40:53.925652Z","end":"2026-07-09T16:40:54.042387Z","steps":["trace[2020464339] 'process raft request' (duration: 116.48458ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.042143Z","caller":"traceutil/trace.go:171","msg":"trace[1586464] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"161.342995ms","start":"2026-07-09T16:40:53.880598Z","end":"2026-07-09T16:40:54.041941Z","steps":["trace[1586464] 'process raft request' (duration: 108.753629ms)","trace[1586464] 'compare' (duration: 52.334889ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:54.225575Z","caller":"traceutil/trace.go:171","msg":"trace[2105475660] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"167.274173ms","start":"2026-07-09T16:40:54.058274Z","end":"2026-07-09T16:40:54.225548Z","steps":["trace[2105475660] 'process raft request' (duration: 167.032206ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.2265Z","caller":"traceutil/trace.go:171","msg":"trace[298209300] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"168.363256ms","start":"2026-07-09T16:40:54.058077Z","end":"2026-07-09T16:40:54.226441Z","steps":["trace[298209300] 'read index received' (duration: 55.134712ms)","trace[298209300] 'applied index is now lower than readState.Index' (duration: 113.226384ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:54.226752Z","caller":"traceutil/trace.go:171","msg":"trace[783280886] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"163.820286ms","start":"2026-07-09T16:40:54.062913Z","end":"2026-07-09T16:40:54.226733Z","steps":["trace[783280886] 'process raft request' (duration: 163.099914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:54.227527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.420029ms","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-07-09T16:40:54.227604Z","caller":"traceutil/trace.go:171","msg":"trace[1214635931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"169.550074ms","start":"2026-07-09T16:40:54.058034Z","end":"2026-07-09T16:40:54.227584Z","steps":["trace[1214635931] 'agreement among raft nodes before linearized reading' (duration: 168.907724ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.225426Z","caller":"traceutil/trace.go:171","msg":"trace[1766640120] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"171.370988ms","start":"2026-07-09T16:40:54.054024Z","end":"2026-07-09T16:40:54.225395Z","steps":["trace[1766640120] 'process raft request' (duration: 171.11061ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.366524Z","caller":"traceutil/trace.go:171","msg":"trace[177857124] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"133.199146ms","start":"2026-07-09T16:40:54.233286Z","end":"2026-07-09T16:40:54.366485Z","steps":["trace[177857124] 'process raft request' (duration: 75.262468ms)","trace[177857124] 'compare' (duration: 57.711941ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:54.367292Z","caller":"traceutil/trace.go:171","msg":"trace[1593551731] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"130.395731ms","start":"2026-07-09T16:40:54.236877Z","end":"2026-07-09T16:40:54.367273Z","steps":["trace[1593551731] 'process raft request' (duration: 129.541745ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.367802Z","caller":"traceutil/trace.go:171","msg":"trace[1109634666] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"127.584405ms","start":"2026-07-09T16:40:54.240198Z","end":"2026-07-09T16:40:54.367782Z","steps":["trace[1109634666] 'process raft request' (duration: 126.886164ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.3682Z","caller":"traceutil/trace.go:171","msg":"trace[1469283049] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:702; }","duration":"110.504331ms","start":"2026-07-09T16:40:54.257658Z","end":"2026-07-09T16:40:54.368162Z","steps":["trace[1469283049] 'read index received' (duration: 50.746317ms)","trace[1469283049] 'applied index is now lower than readState.Index' (duration: 59.755294ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:54.368255Z","caller":"traceutil/trace.go:171","msg":"trace[1544310481] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"123.094127ms","start":"2026-07-09T16:40:54.245126Z","end":"2026-07-09T16:40:54.36822Z","steps":["trace[1544310481] 'process raft request' (duration: 122.094816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:54.368682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.900763ms","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-07-09T16:40:54.368784Z","caller":"traceutil/trace.go:171","msg":"trace[292453452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"111.139351ms","start":"2026-07-09T16:40:54.257623Z","end":"2026-07-09T16:40:54.368762Z","steps":["trace[292453452] 'agreement among raft nodes before linearized reading' (duration: 110.730478ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.368773Z","caller":"traceutil/trace.go:171","msg":"trace[474684694] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"113.252636ms","start":"2026-07-09T16:40:54.25467Z","end":"2026-07-09T16:40:54.367922Z","steps":["trace[474684694] 'process raft request' (duration: 112.612926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:54.369113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.338667ms","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-07-09T16:40:54.369163Z","caller":"traceutil/trace.go:171","msg":"trace[972443268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:675; }","duration":"111.44157ms","start":"2026-07-09T16:40:54.25771Z","end":"2026-07-09T16:40:54.369151Z","steps":["trace[972443268] 'agreement among raft nodes before linearized reading' (duration: 111.308076ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.558533Z","caller":"traceutil/trace.go:171","msg":"trace[333800234] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"122.728665ms","start":"2026-07-09T16:40:54.435777Z","end":"2026-07-09T16:40:54.558506Z","steps":["trace[333800234] 'process raft request' (duration: 99.349848ms)","trace[333800234] 'compare' (duration: 23.236923ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:54.614902Z","caller":"traceutil/trace.go:171","msg":"trace[1910347893] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"114.576256ms","start":"2026-07-09T16:40:54.500304Z","end":"2026-07-09T16:40:54.61488Z","steps":["trace[1910347893] 'process raft request' (duration: 114.39553ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.796323Z","caller":"traceutil/trace.go:171","msg":"trace[454450811] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"111.322725ms","start":"2026-07-09T16:40:54.684976Z","end":"2026-07-09T16:40:54.796299Z","steps":["trace[454450811] 'process raft request' (duration: 111.084798ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.998967Z","caller":"traceutil/trace.go:171","msg":"trace[295071890] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"286.247863ms","start":"2026-07-09T16:40:54.71269Z","end":"2026-07-09T16:40:54.998938Z","steps":["trace[295071890] 'process raft request' (duration: 285.942364ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:54.999133Z","caller":"traceutil/trace.go:171","msg":"trace[2100308833] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:714; }","duration":"136.824659ms","start":"2026-07-09T16:40:54.862273Z","end":"2026-07-09T16:40:54.999098Z","steps":["trace[2100308833] 'read index received' (duration: 136.312573ms)","trace[2100308833] 'applied index is now lower than readState.Index' (duration: 509.764µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:54.999156Z","caller":"traceutil/trace.go:171","msg":"trace[1231728496] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"259.903006ms","start":"2026-07-09T16:40:54.73921Z","end":"2026-07-09T16:40:54.999113Z","steps":["trace[1231728496] 'process raft request' (duration: 259.660568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:54.999414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.118987ms","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-07-09T16:40:54.999493Z","caller":"traceutil/trace.go:171","msg":"trace[2098873922] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"137.215481ms","start":"2026-07-09T16:40:54.862257Z","end":"2026-07-09T16:40:54.999472Z","steps":["trace[2098873922] 'agreement among raft nodes before linearized reading' (duration: 136.953373ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:55.436855Z","caller":"traceutil/trace.go:171","msg":"trace[991642539] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"374.238622ms","start":"2026-07-09T16:40:55.062585Z","end":"2026-07-09T16:40:55.436823Z","steps":["trace[991642539] 'read index received' (duration: 302.600898ms)","trace[991642539] 'applied index is now lower than readState.Index' (duration: 71.635314ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:55.436874Z","caller":"traceutil/trace.go:171","msg":"trace[2100494103] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"433.685366ms","start":"2026-07-09T16:40:55.003154Z","end":"2026-07-09T16:40:55.43684Z","steps":["trace[2100494103] 'process raft request' (duration: 361.902097ms)","trace[2100494103] 'compare' (duration: 71.462179ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:55.437005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:55.003126Z","time spent":"433.819379ms","remote":"127.0.0.1:60040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:40:55.437132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.537322ms","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-07-09T16:40:55.437181Z","caller":"traceutil/trace.go:171","msg":"trace[716948258] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:684; }","duration":"374.632914ms","start":"2026-07-09T16:40:55.062536Z","end":"2026-07-09T16:40:55.437169Z","steps":["trace[716948258] 'agreement among raft nodes before linearized reading' (duration: 374.412057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:55.437222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:55.062509Z","time spent":"374.702248ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-07-09T16:40:55.483603Z","caller":"traceutil/trace.go:171","msg":"trace[1988496566] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"408.017672ms","start":"2026-07-09T16:40:55.075546Z","end":"2026-07-09T16:40:55.483563Z","steps":["trace[1988496566] 'process raft request' (duration: 407.754025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:55.483766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.108148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9xstx\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-07-09T16:40:55.483824Z","caller":"traceutil/trace.go:171","msg":"trace[393746578] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9xstx; range_end:; response_count:1; response_revision:685; }","duration":"273.212012ms","start":"2026-07-09T16:40:55.210599Z","end":"2026-07-09T16:40:55.483811Z","steps":["trace[393746578] 'agreement among raft nodes before linearized reading' (duration: 272.975715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:55.483968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:40:55.075511Z","time spent":"408.185107ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:40:55.483987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.455912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-07-09T16:40:55.484074Z","caller":"traceutil/trace.go:171","msg":"trace[1616042722] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:685; }","duration":"220.608987ms","start":"2026-07-09T16:40:55.263437Z","end":"2026-07-09T16:40:55.484046Z","steps":["trace[1616042722] 'agreement among raft nodes before linearized reading' (duration: 220.39377ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:55.59964Z","caller":"traceutil/trace.go:171","msg":"trace[1728084259] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"102.368522ms","start":"2026-07-09T16:40:55.497247Z","end":"2026-07-09T16:40:55.599616Z","steps":["trace[1728084259] 'process raft request' (duration: 102.205216ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:56.379169Z","caller":"traceutil/trace.go:171","msg":"trace[41469555] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:723; }","duration":"117.372518ms","start":"2026-07-09T16:40:56.261771Z","end":"2026-07-09T16:40:56.379144Z","steps":["trace[41469555] 'read index received' (duration: 93.670299ms)","trace[41469555] 'applied index is now lower than readState.Index' (duration: 23.701019ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:56.37934Z","caller":"traceutil/trace.go:171","msg":"trace[1984131846] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"146.793138ms","start":"2026-07-09T16:40:56.232532Z","end":"2026-07-09T16:40:56.379325Z","steps":["trace[1984131846] 'process raft request' (duration: 123.013168ms)","trace[1984131846] 'compare' (duration: 23.368968ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:56.37982Z","caller":"traceutil/trace.go:171","msg":"trace[179307995] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"133.253559ms","start":"2026-07-09T16:40:56.246524Z","end":"2026-07-09T16:40:56.379777Z","steps":["trace[179307995] 'process raft request' (duration: 132.548267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:56.380741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.950424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-09T16:40:56.380904Z","caller":"traceutil/trace.go:171","msg":"trace[793569203] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"119.058288ms","start":"2026-07-09T16:40:56.261724Z","end":"2026-07-09T16:40:56.380782Z","steps":["trace[793569203] 'agreement among raft nodes before linearized reading' (duration: 117.686646ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:56.557468Z","caller":"traceutil/trace.go:171","msg":"trace[385306933] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"105.93818ms","start":"2026-07-09T16:40:56.451499Z","end":"2026-07-09T16:40:56.557437Z","steps":["trace[385306933] 'read index received' (duration: 77.20015ms)","trace[385306933] 'applied index is now lower than readState.Index' (duration: 28.73691ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:56.557777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.257059ms","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-07-09T16:40:56.557838Z","caller":"traceutil/trace.go:171","msg":"trace[1608873720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"106.384304ms","start":"2026-07-09T16:40:56.451437Z","end":"2026-07-09T16:40:56.557821Z","steps":["trace[1608873720] 'agreement among raft nodes before linearized reading' (duration: 106.179847ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:56.557566Z","caller":"traceutil/trace.go:171","msg":"trace[1206531578] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"124.847263ms","start":"2026-07-09T16:40:56.432674Z","end":"2026-07-09T16:40:56.557521Z","steps":["trace[1206531578] 'process raft request' (duration: 96.055931ms)","trace[1206531578] 'compare' (duration: 28.38116ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:57.368617Z","caller":"traceutil/trace.go:171","msg":"trace[476626334] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"110.24851ms","start":"2026-07-09T16:40:57.25834Z","end":"2026-07-09T16:40:57.368588Z","steps":["trace[476626334] 'process raft request' (duration: 110.146717ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:57.426091Z","caller":"traceutil/trace.go:171","msg":"trace[877247491] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"151.143132ms","start":"2026-07-09T16:40:57.274921Z","end":"2026-07-09T16:40:57.426064Z","steps":["trace[877247491] 'read index received' (duration: 94.069043ms)","trace[877247491] 'applied index is now lower than readState.Index' (duration: 57.073109ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:57.426595Z","caller":"traceutil/trace.go:171","msg":"trace[1676199167] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"161.312489ms","start":"2026-07-09T16:40:57.265265Z","end":"2026-07-09T16:40:57.426578Z","steps":["trace[1676199167] 'process raft request' (duration: 160.642749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:57.427081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.067351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:57.427177Z","caller":"traceutil/trace.go:171","msg":"trace[1930637285] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:699; }","duration":"152.173844ms","start":"2026-07-09T16:40:57.274986Z","end":"2026-07-09T16:40:57.42716Z","steps":["trace[1930637285] 'agreement among raft nodes before linearized reading' (duration: 152.06488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:57.427608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.545461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:57.427998Z","caller":"traceutil/trace.go:171","msg":"trace[75477161] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:699; }","duration":"121.000515ms","start":"2026-07-09T16:40:57.306985Z","end":"2026-07-09T16:40:57.427986Z","steps":["trace[75477161] 'agreement among raft nodes before linearized reading' (duration: 120.567002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:57.42777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.680725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:57.428299Z","caller":"traceutil/trace.go:171","msg":"trace[503970891] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:699; }","duration":"122.272223ms","start":"2026-07-09T16:40:57.306015Z","end":"2026-07-09T16:40:57.428287Z","steps":["trace[503970891] 'agreement among raft nodes before linearized reading' (duration: 121.724757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:57.426695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.74942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:57.428586Z","caller":"traceutil/trace.go:171","msg":"trace[1429796995] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:699; }","duration":"153.649987ms","start":"2026-07-09T16:40:57.274914Z","end":"2026-07-09T16:40:57.428564Z","steps":["trace[1429796995] 'agreement among raft nodes before linearized reading' (duration: 151.722759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:57.427809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.664875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:40:57.427845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.769191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:40:57.42971Z","caller":"traceutil/trace.go:171","msg":"trace[947515085] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:699; }","duration":"153.642998ms","start":"2026-07-09T16:40:57.276039Z","end":"2026-07-09T16:40:57.429682Z","steps":["trace[947515085] 'agreement among raft nodes before linearized reading' (duration: 151.765541ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:57.429891Z","caller":"traceutil/trace.go:171","msg":"trace[1910282306] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:699; }","duration":"123.343406ms","start":"2026-07-09T16:40:57.306114Z","end":"2026-07-09T16:40:57.429458Z","steps":["trace[1910282306] 'agreement among raft nodes before linearized reading' (duration: 121.677825ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:57.725288Z","caller":"traceutil/trace.go:171","msg":"trace[439161788] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"103.709824ms","start":"2026-07-09T16:40:57.621553Z","end":"2026-07-09T16:40:57.725263Z","steps":["trace[439161788] 'process raft request' (duration: 103.583381ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:58.369079Z","caller":"traceutil/trace.go:171","msg":"trace[388020065] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"110.882979ms","start":"2026-07-09T16:40:58.258167Z","end":"2026-07-09T16:40:58.36905Z","steps":["trace[388020065] 'process raft request' (duration: 44.78834ms)","trace[388020065] 'compare' (duration: 65.954016ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:58.630118Z","caller":"traceutil/trace.go:171","msg":"trace[413869347] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"195.392569ms","start":"2026-07-09T16:40:58.434704Z","end":"2026-07-09T16:40:58.630096Z","steps":["trace[413869347] 'process raft request' (duration: 175.72372ms)","trace[413869347] 'compare' (duration: 19.417622ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:59.208757Z","caller":"traceutil/trace.go:171","msg":"trace[178545833] transaction","detail":"{read_only:false; number_of_response:0; response_revision:709; }","duration":"121.032372ms","start":"2026-07-09T16:40:59.087709Z","end":"2026-07-09T16:40:59.208741Z","steps":["trace[178545833] 'process raft request' (duration: 94.39147ms)","trace[178545833] 'compare' (duration: 26.612122ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:59.209714Z","caller":"traceutil/trace.go:171","msg":"trace[728803848] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"109.243481ms","start":"2026-07-09T16:40:59.100454Z","end":"2026-07-09T16:40:59.209697Z","steps":["trace[728803848] 'process raft request' (duration: 109.140098ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:59.319793Z","caller":"traceutil/trace.go:171","msg":"trace[295461203] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"104.046436ms","start":"2026-07-09T16:40:59.215712Z","end":"2026-07-09T16:40:59.319758Z","steps":["trace[295461203] 'process raft request' (duration: 103.793649ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:02.56177Z","caller":"traceutil/trace.go:171","msg":"trace[265111122] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"124.029327ms","start":"2026-07-09T16:41:02.437721Z","end":"2026-07-09T16:41:02.56175Z","steps":["trace[265111122] 'process raft request' (duration: 106.606818ms)","trace[265111122] 'compare' (duration: 17.339287ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:02.739511Z","caller":"traceutil/trace.go:171","msg":"trace[1810169187] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:762; }","duration":"101.154879ms","start":"2026-07-09T16:41:02.638316Z","end":"2026-07-09T16:41:02.739471Z","steps":["trace[1810169187] 'read index received' (duration: 89.754105ms)","trace[1810169187] 'applied index is now lower than readState.Index' (duration: 11.399614ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:02.739632Z","caller":"traceutil/trace.go:171","msg":"trace[18429319] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"163.317766ms","start":"2026-07-09T16:41:02.576302Z","end":"2026-07-09T16:41:02.73962Z","steps":["trace[18429319] 'process raft request' (duration: 163.079219ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:02.739828Z","caller":"traceutil/trace.go:171","msg":"trace[1461828357] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"163.818612ms","start":"2026-07-09T16:41:02.57598Z","end":"2026-07-09T16:41:02.739799Z","steps":["trace[1461828357] 'process raft request' (duration: 152.070818ms)","trace[1461828357] 'compare' (duration: 11.214618ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:02.739893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.55352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-n9kls\" ","response":"range_response_count:1 size:3835"} {"level":"info","ts":"2026-07-09T16:41:02.739928Z","caller":"traceutil/trace.go:171","msg":"trace[1632110917] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-n9kls; range_end:; response_count:1; response_revision:728; }","duration":"101.636922ms","start":"2026-07-09T16:41:02.638282Z","end":"2026-07-09T16:41:02.739918Z","steps":["trace[1632110917] 'agreement among raft nodes before linearized reading' (duration: 101.476507ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:02.757604Z","caller":"traceutil/trace.go:171","msg":"trace[468216452] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"119.131613ms","start":"2026-07-09T16:41:02.638455Z","end":"2026-07-09T16:41:02.757587Z","steps":["trace[468216452] 'process raft request' (duration: 119.02375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.757817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.847098ms","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-07-09T16:41:02.757899Z","caller":"traceutil/trace.go:171","msg":"trace[1856584692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"102.94005ms","start":"2026-07-09T16:41:02.654923Z","end":"2026-07-09T16:41:02.757863Z","steps":["trace[1856584692] 'agreement among raft nodes before linearized reading' (duration: 102.735384ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:02.939672Z","caller":"traceutil/trace.go:171","msg":"trace[1196496365] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"182.082604ms","start":"2026-07-09T16:41:02.757561Z","end":"2026-07-09T16:41:02.939643Z","steps":["trace[1196496365] 'read index received' (duration: 88.460846ms)","trace[1196496365] 'applied index is now lower than readState.Index' (duration: 93.620518ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:41:02.939933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.625438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:41:02.939993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.624914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4543"} {"level":"info","ts":"2026-07-09T16:41:02.939741Z","caller":"traceutil/trace.go:171","msg":"trace[1546462899] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"192.992294ms","start":"2026-07-09T16:41:02.746712Z","end":"2026-07-09T16:41:02.939704Z","steps":["trace[1546462899] 'process raft request' (duration: 99.294034ms)","trace[1546462899] 'compare' (duration: 93.521924ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:02.940024Z","caller":"traceutil/trace.go:171","msg":"trace[1785362662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"199.759251ms","start":"2026-07-09T16:41:02.740247Z","end":"2026-07-09T16:41:02.940007Z","steps":["trace[1785362662] 'agreement among raft nodes before linearized reading' (duration: 199.634066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.939982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.741045ms","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-07-09T16:41:02.940673Z","caller":"traceutil/trace.go:171","msg":"trace[1697826094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"195.485397ms","start":"2026-07-09T16:41:02.745172Z","end":"2026-07-09T16:41:02.940657Z","steps":["trace[1697826094] 'agreement among raft nodes before linearized reading' (duration: 194.617971ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:02.940884Z","caller":"traceutil/trace.go:171","msg":"trace[229888851] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:730; }","duration":"191.321394ms","start":"2026-07-09T16:41:02.749313Z","end":"2026-07-09T16:41:02.940634Z","steps":["trace[229888851] 'agreement among raft nodes before linearized reading' (duration: 190.414278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.942417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.971704ms","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-07-09T16:41:02.943432Z","caller":"traceutil/trace.go:171","msg":"trace[1842455982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:732; }","duration":"127.043035ms","start":"2026-07-09T16:41:02.816367Z","end":"2026-07-09T16:41:02.94341Z","steps":["trace[1842455982] 'agreement among raft nodes before linearized reading' (duration: 125.87879ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:02.94295Z","caller":"traceutil/trace.go:171","msg":"trace[1506779433] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"182.081844ms","start":"2026-07-09T16:41:02.760845Z","end":"2026-07-09T16:41:02.942927Z","steps":["trace[1506779433] 'process raft request' (duration: 181.25402ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:02.942966Z","caller":"traceutil/trace.go:171","msg":"trace[736328079] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"182.086433ms","start":"2026-07-09T16:41:02.760809Z","end":"2026-07-09T16:41:02.942895Z","steps":["trace[736328079] 'process raft request' (duration: 181.172077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:02.943078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.249795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-07-09T16:41:02.943919Z","caller":"traceutil/trace.go:171","msg":"trace[1284033285] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:732; }","duration":"110.12872ms","start":"2026-07-09T16:41:02.833775Z","end":"2026-07-09T16:41:02.943904Z","steps":["trace[1284033285] 'agreement among raft nodes before linearized reading' (duration: 109.227954ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:03.458334Z","caller":"traceutil/trace.go:171","msg":"trace[703149671] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"103.334654ms","start":"2026-07-09T16:41:03.354972Z","end":"2026-07-09T16:41:03.458306Z","steps":["trace[703149671] 'process raft request' (duration: 103.21042ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:05.107049Z","caller":"traceutil/trace.go:171","msg":"trace[941597017] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"125.086439ms","start":"2026-07-09T16:41:04.981935Z","end":"2026-07-09T16:41:05.107021Z","steps":["trace[941597017] 'process raft request' (duration: 101.278025ms)","trace[941597017] 'compare' (duration: 23.565447ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:05.191983Z","caller":"traceutil/trace.go:171","msg":"trace[71992914] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:780; }","duration":"184.573636ms","start":"2026-07-09T16:41:05.007389Z","end":"2026-07-09T16:41:05.191962Z","steps":["trace[71992914] 'read index received' (duration: 75.938783ms)","trace[71992914] 'applied index is now lower than readState.Index' (duration: 108.633763ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:05.192208Z","caller":"traceutil/trace.go:171","msg":"trace[35987778] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"202.152193ms","start":"2026-07-09T16:41:04.990045Z","end":"2026-07-09T16:41:05.192197Z","steps":["trace[35987778] 'process raft request' (duration: 201.746513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:05.192529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.118921ms","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-07-09T16:41:05.192559Z","caller":"traceutil/trace.go:171","msg":"trace[775340677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"185.210394ms","start":"2026-07-09T16:41:05.007342Z","end":"2026-07-09T16:41:05.192552Z","steps":["trace[775340677] 'agreement among raft nodes before linearized reading' (duration: 184.691949ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:05.523939Z","caller":"traceutil/trace.go:171","msg":"trace[71775694] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"102.575868ms","start":"2026-07-09T16:41:05.421339Z","end":"2026-07-09T16:41:05.523915Z","steps":["trace[71775694] 'process raft request' (duration: 102.513157ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:05.523982Z","caller":"traceutil/trace.go:171","msg":"trace[332017034] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"105.281817ms","start":"2026-07-09T16:41:05.418681Z","end":"2026-07-09T16:41:05.523963Z","steps":["trace[332017034] 'process raft request' (duration: 103.237438ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:09.24076Z","caller":"traceutil/trace.go:171","msg":"trace[1661175589] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"101.110214ms","start":"2026-07-09T16:41:09.139627Z","end":"2026-07-09T16:41:09.240737Z","steps":["trace[1661175589] 'read index received' (duration: 100.95391ms)","trace[1661175589] 'applied index is now lower than readState.Index' (duration: 155.374µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:09.240832Z","caller":"traceutil/trace.go:171","msg":"trace[1905869642] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"241.81604ms","start":"2026-07-09T16:41:08.998975Z","end":"2026-07-09T16:41:09.240791Z","steps":["trace[1905869642] 'process raft request' (duration: 241.622595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.241043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.403122ms","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-07-09T16:41:09.241092Z","caller":"traceutil/trace.go:171","msg":"trace[1853935733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"101.501804ms","start":"2026-07-09T16:41:09.139579Z","end":"2026-07-09T16:41:09.24108Z","steps":["trace[1853935733] 'agreement among raft nodes before linearized reading' (duration: 101.250227ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:09.616533Z","caller":"traceutil/trace.go:171","msg":"trace[341323270] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"175.109884ms","start":"2026-07-09T16:41:09.441384Z","end":"2026-07-09T16:41:09.616494Z","steps":["trace[341323270] 'process raft request' (duration: 174.845197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:09.781767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.809667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:41:09.781961Z","caller":"traceutil/trace.go:171","msg":"trace[300897763] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"202.857353ms","start":"2026-07-09T16:41:09.579073Z","end":"2026-07-09T16:41:09.78193Z","steps":["trace[300897763] 'process raft request' (duration: 81.605894ms)","trace[300897763] 'compare' (duration: 120.647373ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:11.038578Z","caller":"traceutil/trace.go:171","msg":"trace[232101190] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"299.662235ms","start":"2026-07-09T16:41:10.738882Z","end":"2026-07-09T16:41:11.038544Z","steps":["trace[232101190] 'read index received' (duration: 299.315516ms)","trace[232101190] 'applied index is now lower than readState.Index' (duration: 345.489µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:11.038662Z","caller":"traceutil/trace.go:171","msg":"trace[963460643] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"314.918372ms","start":"2026-07-09T16:41:10.723698Z","end":"2026-07-09T16:41:11.038617Z","steps":["trace[963460643] 'process raft request' (duration: 314.593283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:11.038782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.847261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:41:11.038851Z","caller":"traceutil/trace.go:171","msg":"trace[884987529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"299.994135ms","start":"2026-07-09T16:41:10.738844Z","end":"2026-07-09T16:41:11.038838Z","steps":["trace[884987529] 'agreement among raft nodes before linearized reading' (duration: 299.834051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:11.038902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:10.738831Z","time spent":"300.061577ms","remote":"127.0.0.1:59976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:41:11.038899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:41:10.723677Z","time spent":"315.078796ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:41:11.558921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.315025ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-09T16:41:13.466941Z","caller":"traceutil/trace.go:171","msg":"trace[1960589572] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"180.976252ms","start":"2026-07-09T16:41:13.285941Z","end":"2026-07-09T16:41:13.466917Z","steps":["trace[1960589572] 'read index received' (duration: 180.810998ms)","trace[1960589572] 'applied index is now lower than readState.Index' (duration: 164.344µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:41:13.46701Z","caller":"traceutil/trace.go:171","msg":"trace[1353464877] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"187.917993ms","start":"2026-07-09T16:41:13.279064Z","end":"2026-07-09T16:41:13.466982Z","steps":["trace[1353464877] 'process raft request' (duration: 187.703447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:41:13.467256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.29654ms","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-07-09T16:41:13.467449Z","caller":"traceutil/trace.go:171","msg":"trace[1986681848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"181.531378ms","start":"2026-07-09T16:41:13.285897Z","end":"2026-07-09T16:41:13.467429Z","steps":["trace[1986681848] 'agreement among raft nodes before linearized reading' (duration: 181.149487ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:17.319112Z","caller":"traceutil/trace.go:171","msg":"trace[189391912] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"180.663573ms","start":"2026-07-09T16:41:17.138424Z","end":"2026-07-09T16:41:17.319088Z","steps":["trace[189391912] 'process raft request' (duration: 180.498149ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:41:17.427382Z","caller":"traceutil/trace.go:171","msg":"trace[836865369] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"112.246399ms","start":"2026-07-09T16:41:17.315105Z","end":"2026-07-09T16:41:17.427351Z","steps":["trace[836865369] 'process raft request' (duration: 96.415442ms)","trace[836865369] 'compare' (duration: 15.658222ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:07.902746Z","caller":"traceutil/trace.go:171","msg":"trace[1189817823] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"145.995143ms","start":"2026-07-09T16:42:07.756725Z","end":"2026-07-09T16:42:07.90272Z","steps":["trace[1189817823] 'process raft request' (duration: 52.791171ms)","trace[1189817823] 'compare' (duration: 93.041549ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:07.907217Z","caller":"traceutil/trace.go:171","msg":"trace[315255622] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"148.110811ms","start":"2026-07-09T16:42:07.759079Z","end":"2026-07-09T16:42:07.90719Z","steps":["trace[315255622] 'process raft request' (duration: 147.987947ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:07.90734Z","caller":"traceutil/trace.go:171","msg":"trace[1172341457] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"146.168277ms","start":"2026-07-09T16:42:07.761163Z","end":"2026-07-09T16:42:07.907331Z","steps":["trace[1172341457] 'process raft request' (duration: 146.047984ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:07.907423Z","caller":"traceutil/trace.go:171","msg":"trace[2068963789] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"147.6325ms","start":"2026-07-09T16:42:07.759774Z","end":"2026-07-09T16:42:07.907407Z","steps":["trace[2068963789] 'process raft request' (duration: 147.372173ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:07.907534Z","caller":"traceutil/trace.go:171","msg":"trace[1466686001] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"147.461847ms","start":"2026-07-09T16:42:07.760051Z","end":"2026-07-09T16:42:07.907513Z","steps":["trace[1466686001] 'process raft request' (duration: 147.131219ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:08.152058Z","caller":"traceutil/trace.go:171","msg":"trace[156655114] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"153.504871ms","start":"2026-07-09T16:42:07.998532Z","end":"2026-07-09T16:42:08.152037Z","steps":["trace[156655114] 'process raft request' (duration: 153.364349ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:08.152439Z","caller":"traceutil/trace.go:171","msg":"trace[484423048] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"153.518272ms","start":"2026-07-09T16:42:07.99891Z","end":"2026-07-09T16:42:08.152429Z","steps":["trace[484423048] 'process raft request' (duration: 153.084353ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:08.152722Z","caller":"traceutil/trace.go:171","msg":"trace[1429188639] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"152.430457ms","start":"2026-07-09T16:42:08.000273Z","end":"2026-07-09T16:42:08.152704Z","steps":["trace[1429188639] 'process raft request' (duration: 152.019498ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:08.153312Z","caller":"traceutil/trace.go:171","msg":"trace[1172579440] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"122.609484ms","start":"2026-07-09T16:42:08.03068Z","end":"2026-07-09T16:42:08.15329Z","steps":["trace[1172579440] 'process raft request' (duration: 121.866857ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:11.823654Z","caller":"traceutil/trace.go:171","msg":"trace[578002838] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"197.423075ms","start":"2026-07-09T16:42:11.6262Z","end":"2026-07-09T16:42:11.823623Z","steps":["trace[578002838] 'process raft request' (duration: 197.106608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:27.772449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.449646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/pxc-operator-69cb5bbdb9-tt52f\" ","response":"range_response_count:1 size:3255"} {"level":"info","ts":"2026-07-09T16:42:27.772595Z","caller":"traceutil/trace.go:171","msg":"trace[2309826] range","detail":"{range_begin:/registry/pods/openstack/pxc-operator-69cb5bbdb9-tt52f; range_end:; response_count:1; response_revision:1745; }","duration":"105.587849ms","start":"2026-07-09T16:42:27.666966Z","end":"2026-07-09T16:42:27.772554Z","steps":["trace[2309826] 'agreement among raft nodes before linearized reading' (duration: 105.354095ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:36.809355Z","caller":"traceutil/trace.go:171","msg":"trace[159528725] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"124.055687ms","start":"2026-07-09T16:42:36.685267Z","end":"2026-07-09T16:42:36.809323Z","steps":["trace[159528725] 'process raft request' (duration: 123.570566ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:37.244151Z","caller":"traceutil/trace.go:171","msg":"trace[1917406683] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"389.437192ms","start":"2026-07-09T16:42:36.854672Z","end":"2026-07-09T16:42:37.244109Z","steps":["trace[1917406683] 'process raft request' (duration: 389.027774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:37.244421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.748732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-09T16:42:37.244494Z","caller":"traceutil/trace.go:171","msg":"trace[4138503] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1877; }","duration":"382.884735ms","start":"2026-07-09T16:42:36.861569Z","end":"2026-07-09T16:42:37.244454Z","steps":["trace[4138503] 'agreement among raft nodes before linearized reading' (duration: 382.61684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:37.244541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:36.854631Z","time spent":"389.747468ms","remote":"127.0.0.1:60154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10967,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:37.244523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:36.861543Z","time spent":"382.972587ms","remote":"127.0.0.1:36956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-09T16:42:37.244017Z","caller":"traceutil/trace.go:171","msg":"trace[821748145] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"382.365465ms","start":"2026-07-09T16:42:36.861622Z","end":"2026-07-09T16:42:37.243987Z","steps":["trace[821748145] 'read index received' (duration: 381.968956ms)","trace[821748145] 'applied index is now lower than readState.Index' (duration: 395.419µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:37.540132Z","caller":"traceutil/trace.go:171","msg":"trace[1878540559] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"295.33194ms","start":"2026-07-09T16:42:37.244752Z","end":"2026-07-09T16:42:37.540084Z","steps":["trace[1878540559] 'read index received' (duration: 282.294567ms)","trace[1878540559] 'applied index is now lower than readState.Index' (duration: 13.035903ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:37.540434Z","caller":"traceutil/trace.go:171","msg":"trace[2028943938] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"549.93004ms","start":"2026-07-09T16:42:36.990442Z","end":"2026-07-09T16:42:37.540372Z","steps":["trace[2028943938] 'process raft request' (duration: 536.498179ms)","trace[2028943938] 'compare' (duration: 12.701576ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:42:37.540786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.59967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:42:37.540841Z","caller":"traceutil/trace.go:171","msg":"trace[499745637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1878; }","duration":"524.715392ms","start":"2026-07-09T16:42:37.016109Z","end":"2026-07-09T16:42:37.540825Z","steps":["trace[499745637] 'agreement among raft nodes before linearized reading' (duration: 524.326475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:37.540874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:36.990407Z","time spent":"550.37405ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:37.540916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:37.016086Z","time spent":"524.792434ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T16:42:37.60761Z","caller":"traceutil/trace.go:171","msg":"trace[1484871932] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"357.50181ms","start":"2026-07-09T16:42:37.250068Z","end":"2026-07-09T16:42:37.60757Z","steps":["trace[1484871932] 'process raft request' (duration: 357.260215ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:37.607785Z","caller":"traceutil/trace.go:171","msg":"trace[1674855392] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"356.495199ms","start":"2026-07-09T16:42:37.251239Z","end":"2026-07-09T16:42:37.607734Z","steps":["trace[1674855392] 'process raft request' (duration: 356.250603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:37.60784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:37.250048Z","time spent":"357.700464ms","remote":"127.0.0.1:60228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:37.607969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:37.25123Z","time spent":"356.636092ms","remote":"127.0.0.1:60142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:42:37.608166Z","caller":"traceutil/trace.go:171","msg":"trace[237299645] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"356.60999ms","start":"2026-07-09T16:42:37.25154Z","end":"2026-07-09T16:42:37.60815Z","steps":["trace[237299645] 'process raft request' (duration: 356.038318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:37.608256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.208801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:42:37.608301Z","caller":"traceutil/trace.go:171","msg":"trace[789158890] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1883; }","duration":"254.362055ms","start":"2026-07-09T16:42:37.353927Z","end":"2026-07-09T16:42:37.608289Z","steps":["trace[789158890] 'agreement among raft nodes before linearized reading' (duration: 254.228971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:37.608314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:37.251532Z","time spent":"356.712642ms","remote":"127.0.0.1:60228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:42:37.607655Z","caller":"traceutil/trace.go:171","msg":"trace[1599146173] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"356.276805ms","start":"2026-07-09T16:42:37.25135Z","end":"2026-07-09T16:42:37.607627Z","steps":["trace[1599146173] 'process raft request' (duration: 356.183953ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:37.608581Z","caller":"traceutil/trace.go:171","msg":"trace[460109730] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"348.198695ms","start":"2026-07-09T16:42:37.260372Z","end":"2026-07-09T16:42:37.608571Z","steps":["trace[460109730] 'process raft request' (duration: 347.394409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:37.60869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:37.26035Z","time spent":"348.310917ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:37.608854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:37.251344Z","time spent":"357.301616ms","remote":"127.0.0.1:60142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:42:37.951981Z","caller":"traceutil/trace.go:171","msg":"trace[565001434] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1966; }","duration":"136.84572ms","start":"2026-07-09T16:42:37.815109Z","end":"2026-07-09T16:42:37.951955Z","steps":["trace[565001434] 'read index received' (duration: 59.32135ms)","trace[565001434] 'applied index is now lower than readState.Index' (duration: 77.52305ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:37.952077Z","caller":"traceutil/trace.go:171","msg":"trace[1631751232] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"405.134045ms","start":"2026-07-09T16:42:37.546901Z","end":"2026-07-09T16:42:37.952035Z","steps":["trace[1631751232] 'process raft request' (duration: 327.468291ms)","trace[1631751232] 'compare' (duration: 77.271975ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:42:37.952533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:37.546873Z","time spent":"405.447681ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:37.952812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.685206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f83fcda7-a5eb-4a49-a11f-4fdc0becf327\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-07-09T16:42:37.960867Z","caller":"traceutil/trace.go:171","msg":"trace[858455782] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f83fcda7-a5eb-4a49-a11f-4fdc0becf327; range_end:; response_count:1; response_revision:1884; }","duration":"137.786818ms","start":"2026-07-09T16:42:37.815065Z","end":"2026-07-09T16:42:37.952852Z","steps":["trace[858455782] 'agreement among raft nodes before linearized reading' (duration: 137.042562ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:41.281289Z","caller":"traceutil/trace.go:171","msg":"trace[586806657] linearizableReadLoop","detail":"{readStateIndex:1996; appliedIndex:1995; }","duration":"143.733401ms","start":"2026-07-09T16:42:41.137536Z","end":"2026-07-09T16:42:41.28127Z","steps":["trace[586806657] 'read index received' (duration: 143.546088ms)","trace[586806657] 'applied index is now lower than readState.Index' (duration: 186.623µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:41.281709Z","caller":"traceutil/trace.go:171","msg":"trace[73771120] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1913; }","duration":"235.24034ms","start":"2026-07-09T16:42:41.046458Z","end":"2026-07-09T16:42:41.281699Z","steps":["trace[73771120] 'process raft request' (duration: 234.694209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:41.281851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.281042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:42:41.281976Z","caller":"traceutil/trace.go:171","msg":"trace[673476056] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1913; }","duration":"144.430448ms","start":"2026-07-09T16:42:41.137504Z","end":"2026-07-09T16:42:41.281935Z","steps":["trace[673476056] 'agreement among raft nodes before linearized reading' (duration: 144.246224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:43.282553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.703233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:42:43.28269Z","caller":"traceutil/trace.go:171","msg":"trace[1375775480] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1938; }","duration":"101.885327ms","start":"2026-07-09T16:42:43.180784Z","end":"2026-07-09T16:42:43.28267Z","steps":["trace[1375775480] 'count revisions from in-memory index tree' (duration: 101.643912ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:50.456882Z","caller":"traceutil/trace.go:171","msg":"trace[1420312868] linearizableReadLoop","detail":"{readStateIndex:2072; appliedIndex:2071; }","duration":"424.849366ms","start":"2026-07-09T16:42:50.032015Z","end":"2026-07-09T16:42:50.456864Z","steps":["trace[1420312868] 'read index received' (duration: 424.774094ms)","trace[1420312868] 'applied index is now lower than readState.Index' (duration: 74.532µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:50.456917Z","caller":"traceutil/trace.go:171","msg":"trace[977579022] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"428.380499ms","start":"2026-07-09T16:42:50.028515Z","end":"2026-07-09T16:42:50.456896Z","steps":["trace[977579022] 'process raft request' (duration: 428.242227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:50.457028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:50.028496Z","time spent":"428.466551ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:50.457178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.155333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T16:42:50.457207Z","caller":"traceutil/trace.go:171","msg":"trace[1478966056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1987; }","duration":"425.248225ms","start":"2026-07-09T16:42:50.031953Z","end":"2026-07-09T16:42:50.457201Z","steps":["trace[1478966056] 'agreement among raft nodes before linearized reading' (duration: 424.9991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:50.457287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:50.031935Z","time spent":"425.340587ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T16:42:50.527138Z","caller":"traceutil/trace.go:171","msg":"trace[418868417] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"399.941287ms","start":"2026-07-09T16:42:50.127175Z","end":"2026-07-09T16:42:50.527116Z","steps":["trace[418868417] 'process raft request' (duration: 372.419964ms)","trace[418868417] 'compare' (duration: 27.229486ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:42:50.527273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:50.127145Z","time spent":"400.05161ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:50.527439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.159805ms","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-07-09T16:42:50.527521Z","caller":"traceutil/trace.go:171","msg":"trace[1032833496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1989; }","duration":"338.266837ms","start":"2026-07-09T16:42:50.189241Z","end":"2026-07-09T16:42:50.527508Z","steps":["trace[1032833496] 'agreement among raft nodes before linearized reading' (duration: 337.883499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:50.527576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:50.189223Z","time spent":"338.318927ms","remote":"127.0.0.1:60222","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-07-09T16:42:50.527459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.477532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-09T16:42:50.527582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.211951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T16:42:50.527645Z","caller":"traceutil/trace.go:171","msg":"trace[60542472] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1989; }","duration":"186.700908ms","start":"2026-07-09T16:42:50.34092Z","end":"2026-07-09T16:42:50.527621Z","steps":["trace[60542472] 'agreement among raft nodes before linearized reading' (duration: 186.222278ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:50.527675Z","caller":"traceutil/trace.go:171","msg":"trace[1908337742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1989; }","duration":"329.316054ms","start":"2026-07-09T16:42:50.198338Z","end":"2026-07-09T16:42:50.527654Z","steps":["trace[1908337742] 'agreement among raft nodes before linearized reading' (duration: 328.824804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:50.527838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.907054ms","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-07-09T16:42:50.527985Z","caller":"traceutil/trace.go:171","msg":"trace[1430803297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1989; }","duration":"325.114218ms","start":"2026-07-09T16:42:50.202839Z","end":"2026-07-09T16:42:50.527953Z","steps":["trace[1430803297] 'agreement among raft nodes before linearized reading' (duration: 324.314052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:50.528071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:50.202832Z","time spent":"325.22111ms","remote":"127.0.0.1:60222","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-07-09T16:42:50.527863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:50.198276Z","time spent":"329.492967ms","remote":"127.0.0.1:60222","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-07-09T16:42:50.687226Z","caller":"traceutil/trace.go:171","msg":"trace[249497202] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2076; }","duration":"120.850071ms","start":"2026-07-09T16:42:50.566331Z","end":"2026-07-09T16:42:50.687181Z","steps":["trace[249497202] 'read index received' (duration: 85.131901ms)","trace[249497202] 'applied index is now lower than readState.Index' (duration: 35.71601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:42:50.68762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.250499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11979"} {"level":"info","ts":"2026-07-09T16:42:50.687694Z","caller":"traceutil/trace.go:171","msg":"trace[610208213] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1992; }","duration":"121.402703ms","start":"2026-07-09T16:42:50.566274Z","end":"2026-07-09T16:42:50.687676Z","steps":["trace[610208213] 'agreement among raft nodes before linearized reading' (duration: 121.068466ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:50.687784Z","caller":"traceutil/trace.go:171","msg":"trace[243136489] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"153.889436ms","start":"2026-07-09T16:42:50.533855Z","end":"2026-07-09T16:42:50.687745Z","steps":["trace[243136489] 'process raft request' (duration: 117.612284ms)","trace[243136489] 'compare' (duration: 35.458685ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:42:54.849376Z","caller":"traceutil/trace.go:171","msg":"trace[771015956] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"426.987489ms","start":"2026-07-09T16:42:54.422361Z","end":"2026-07-09T16:42:54.849348Z","steps":["trace[771015956] 'process raft request' (duration: 426.840166ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:54.849545Z","caller":"traceutil/trace.go:171","msg":"trace[691330437] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2105; }","duration":"375.816689ms","start":"2026-07-09T16:42:54.473693Z","end":"2026-07-09T16:42:54.849509Z","steps":["trace[691330437] 'read index received' (duration: 375.796829ms)","trace[691330437] 'applied index is now lower than readState.Index' (duration: 16.89µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:42:54.849583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:54.422347Z","time spent":"427.105952ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:54.849881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.163017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:42:54.849985Z","caller":"traceutil/trace.go:171","msg":"trace[1943921698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2019; }","duration":"376.352839ms","start":"2026-07-09T16:42:54.473608Z","end":"2026-07-09T16:42:54.849961Z","steps":["trace[1943921698] 'agreement among raft nodes before linearized reading' (duration: 375.997872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:54.850038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:54.473573Z","time spent":"376.456053ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:42:54.872968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.745619ms","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-07-09T16:42:54.873052Z","caller":"traceutil/trace.go:171","msg":"trace[2115418482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2021; }","duration":"322.894334ms","start":"2026-07-09T16:42:54.550138Z","end":"2026-07-09T16:42:54.873032Z","steps":["trace[2115418482] 'agreement among raft nodes before linearized reading' (duration: 322.668159ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:42:54.872907Z","caller":"traceutil/trace.go:171","msg":"trace[1843710576] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"328.806503ms","start":"2026-07-09T16:42:54.543996Z","end":"2026-07-09T16:42:54.872803Z","steps":["trace[1843710576] 'process raft request' (duration: 324.021546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:54.873096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:54.55012Z","time spent":"322.967945ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:42:54.87332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:54.543973Z","time spent":"329.15903ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:42:54.873426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.753977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-09T16:42:54.873467Z","caller":"traceutil/trace.go:171","msg":"trace[206883896] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2021; }","duration":"313.799607ms","start":"2026-07-09T16:42:54.559653Z","end":"2026-07-09T16:42:54.873452Z","steps":["trace[206883896] 'agreement among raft nodes before linearized reading' (duration: 313.707166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:54.87352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:54.559647Z","time spent":"313.84437ms","remote":"127.0.0.1:36956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-09T16:42:54.873654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.102905ms","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-07-09T16:42:54.873741Z","caller":"traceutil/trace.go:171","msg":"trace[1511602954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2021; }","duration":"316.215437ms","start":"2026-07-09T16:42:54.557504Z","end":"2026-07-09T16:42:54.873719Z","steps":["trace[1511602954] 'agreement among raft nodes before linearized reading' (duration: 315.998743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:54.873787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:54.557493Z","time spent":"316.285398ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:42:54.873866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.796209ms","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-07-09T16:42:54.873905Z","caller":"traceutil/trace.go:171","msg":"trace[1673586409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2021; }","duration":"316.8627ms","start":"2026-07-09T16:42:54.557031Z","end":"2026-07-09T16:42:54.873894Z","steps":["trace[1673586409] 'agreement among raft nodes before linearized reading' (duration: 316.753878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:42:54.873934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:42:54.557009Z","time spent":"316.919652ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:42:54.873335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.362272ms","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-07-09T16:42:54.874179Z","caller":"traceutil/trace.go:171","msg":"trace[1186455978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2021; }","duration":"170.24384ms","start":"2026-07-09T16:42:54.703914Z","end":"2026-07-09T16:42:54.874158Z","steps":["trace[1186455978] 'agreement among raft nodes before linearized reading' (duration: 169.301041ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:17.122275Z","caller":"traceutil/trace.go:171","msg":"trace[1585601947] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"126.99001ms","start":"2026-07-09T16:43:16.995261Z","end":"2026-07-09T16:43:17.122251Z","steps":["trace[1585601947] 'process raft request' (duration: 122.517342ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:17.122891Z","caller":"traceutil/trace.go:171","msg":"trace[991233072] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2236; }","duration":"126.501611ms","start":"2026-07-09T16:43:16.996374Z","end":"2026-07-09T16:43:17.122875Z","steps":["trace[991233072] 'read index received' (duration: 10.795463ms)","trace[991233072] 'applied index is now lower than readState.Index' (duration: 115.705208ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:17.122987Z","caller":"traceutil/trace.go:171","msg":"trace[1352763218] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"127.49742ms","start":"2026-07-09T16:43:16.99548Z","end":"2026-07-09T16:43:17.122977Z","steps":["trace[1352763218] 'process raft request' (duration: 126.711865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:17.123288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.471166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-09T16:43:17.123398Z","caller":"traceutil/trace.go:171","msg":"trace[32364220] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2150; }","duration":"103.626259ms","start":"2026-07-09T16:43:17.01975Z","end":"2026-07-09T16:43:17.123376Z","steps":["trace[32364220] 'agreement among raft nodes before linearized reading' (duration: 103.389094ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:17.123622Z","caller":"traceutil/trace.go:171","msg":"trace[1404597466] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"124.730536ms","start":"2026-07-09T16:43:16.998871Z","end":"2026-07-09T16:43:17.123602Z","steps":["trace[1404597466] 'process raft request' (duration: 123.899249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:17.123546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.154094ms","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-07-09T16:43:17.124018Z","caller":"traceutil/trace.go:171","msg":"trace[801207938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2150; }","duration":"127.615993ms","start":"2026-07-09T16:43:16.99635Z","end":"2026-07-09T16:43:17.123966Z","steps":["trace[801207938] 'agreement among raft nodes before linearized reading' (duration: 127.079502ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:19.110369Z","caller":"traceutil/trace.go:171","msg":"trace[2013360551] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"113.273955ms","start":"2026-07-09T16:43:18.997031Z","end":"2026-07-09T16:43:19.110305Z","steps":["trace[2013360551] 'process raft request' (duration: 112.918088ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:19.374809Z","caller":"traceutil/trace.go:171","msg":"trace[646574215] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"260.115971ms","start":"2026-07-09T16:43:19.114653Z","end":"2026-07-09T16:43:19.374769Z","steps":["trace[646574215] 'process raft request' (duration: 252.97829ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:19.374853Z","caller":"traceutil/trace.go:171","msg":"trace[1080803757] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2245; }","duration":"243.200499ms","start":"2026-07-09T16:43:19.131628Z","end":"2026-07-09T16:43:19.374828Z","steps":["trace[1080803757] 'read index received' (duration: 236.010807ms)","trace[1080803757] 'applied index is now lower than readState.Index' (duration: 7.18727ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:19.375108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.464873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:43:19.375179Z","caller":"traceutil/trace.go:171","msg":"trace[849542842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2156; }","duration":"243.591675ms","start":"2026-07-09T16:43:19.131568Z","end":"2026-07-09T16:43:19.37516Z","steps":["trace[849542842] 'agreement among raft nodes before linearized reading' (duration: 243.349879ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:19.375148Z","caller":"traceutil/trace.go:171","msg":"trace[1194168462] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"241.980184ms","start":"2026-07-09T16:43:19.133068Z","end":"2026-07-09T16:43:19.375049Z","steps":["trace[1194168462] 'process raft request' (duration: 241.584766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:19.375334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.865742ms","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-07-09T16:43:19.375363Z","caller":"traceutil/trace.go:171","msg":"trace[922765085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2156; }","duration":"241.915562ms","start":"2026-07-09T16:43:19.133441Z","end":"2026-07-09T16:43:19.375357Z","steps":["trace[922765085] 'agreement among raft nodes before linearized reading' (duration: 241.845121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:19.375396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.661277ms","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-07-09T16:43:19.375459Z","caller":"traceutil/trace.go:171","msg":"trace[983504098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2156; }","duration":"243.782108ms","start":"2026-07-09T16:43:19.13166Z","end":"2026-07-09T16:43:19.375442Z","steps":["trace[983504098] 'agreement among raft nodes before linearized reading' (duration: 243.600935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:19.375472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.315122ms","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-07-09T16:43:19.375537Z","caller":"traceutil/trace.go:171","msg":"trace[1083996243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2156; }","duration":"235.409224ms","start":"2026-07-09T16:43:19.140115Z","end":"2026-07-09T16:43:19.375525Z","steps":["trace[1083996243] 'agreement among raft nodes before linearized reading' (duration: 235.156759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:19.375537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.590668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-09T16:43:19.375631Z","caller":"traceutil/trace.go:171","msg":"trace[1018158932] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2156; }","duration":"234.696351ms","start":"2026-07-09T16:43:19.140903Z","end":"2026-07-09T16:43:19.375599Z","steps":["trace[1018158932] 'agreement among raft nodes before linearized reading' (duration: 234.485766ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:07.64989Z","caller":"traceutil/trace.go:171","msg":"trace[2097381084] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"105.084534ms","start":"2026-07-09T16:44:07.544768Z","end":"2026-07-09T16:44:07.649852Z","steps":["trace[2097381084] 'process raft request' (duration: 104.794968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.873494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.379112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-09T16:44:08.873561Z","caller":"traceutil/trace.go:171","msg":"trace[1084623773] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2529; }","duration":"197.452572ms","start":"2026-07-09T16:44:08.676093Z","end":"2026-07-09T16:44:08.873546Z","steps":["trace[1084623773] 'range keys from in-memory index tree' (duration: 197.25845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:40.38501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.855555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:44:40.38571Z","caller":"traceutil/trace.go:171","msg":"trace[70212600] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"373.237506ms","start":"2026-07-09T16:44:40.01244Z","end":"2026-07-09T16:44:40.385678Z","steps":["trace[70212600] 'process raft request' (duration: 196.593347ms)","trace[70212600] 'compare' (duration: 175.579565ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:40.385772Z","caller":"traceutil/trace.go:171","msg":"trace[1382100366] linearizableReadLoop","detail":"{readStateIndex:2815; appliedIndex:2814; }","duration":"212.117863ms","start":"2026-07-09T16:44:40.173637Z","end":"2026-07-09T16:44:40.385755Z","steps":["trace[1382100366] 'read index received' (duration: 35.294247ms)","trace[1382100366] 'applied index is now lower than readState.Index' (duration: 176.822226ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:40.38584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.198295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:44:40.385866Z","caller":"traceutil/trace.go:171","msg":"trace[788422108] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2705; }","duration":"212.265097ms","start":"2026-07-09T16:44:40.173593Z","end":"2026-07-09T16:44:40.385858Z","steps":["trace[788422108] 'agreement among raft nodes before linearized reading' (duration: 212.212735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:40.385856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:40.012417Z","time spent":"373.36956ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:40.386663Z","caller":"traceutil/trace.go:171","msg":"trace[1665271379] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"161.102683ms","start":"2026-07-09T16:44:40.225549Z","end":"2026-07-09T16:44:40.386652Z","steps":["trace[1665271379] 'process raft request' (duration: 159.653847ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:46.295745Z","caller":"traceutil/trace.go:171","msg":"trace[1452293493] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"171.957798ms","start":"2026-07-09T16:44:46.123758Z","end":"2026-07-09T16:44:46.295716Z","steps":["trace[1452293493] 'process raft request' (duration: 171.769602ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:58.126652Z","caller":"traceutil/trace.go:171","msg":"trace[477997662] linearizableReadLoop","detail":"{readStateIndex:2913; appliedIndex:2911; }","duration":"126.122514ms","start":"2026-07-09T16:44:58.000506Z","end":"2026-07-09T16:44:58.126628Z","steps":["trace[477997662] 'read index received' (duration: 21.970269ms)","trace[477997662] 'applied index is now lower than readState.Index' (duration: 104.151285ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:58.126773Z","caller":"traceutil/trace.go:171","msg":"trace[1183991193] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"126.389621ms","start":"2026-07-09T16:44:58.000371Z","end":"2026-07-09T16:44:58.126761Z","steps":["trace[1183991193] 'process raft request' (duration: 45.651358ms)","trace[1183991193] 'compare' (duration: 80.290059ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:58.126967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.450684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-09T16:44:58.126994Z","caller":"traceutil/trace.go:171","msg":"trace[1144596286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2799; }","duration":"126.518225ms","start":"2026-07-09T16:44:58.000469Z","end":"2026-07-09T16:44:58.126987Z","steps":["trace[1144596286] 'agreement among raft nodes before linearized reading' (duration: 126.394691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:58.127157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.113234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T16:44:58.127226Z","caller":"traceutil/trace.go:171","msg":"trace[1165663985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2799; }","duration":"126.200946ms","start":"2026-07-09T16:44:58.001009Z","end":"2026-07-09T16:44:58.12721Z","steps":["trace[1165663985] 'agreement among raft nodes before linearized reading' (duration: 126.051721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:29.394652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.968042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-09T16:48:29.394731Z","caller":"traceutil/trace.go:171","msg":"trace[390789717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4531; }","duration":"101.078574ms","start":"2026-07-09T16:48:29.293641Z","end":"2026-07-09T16:48:29.394719Z","steps":["trace[390789717] 'agreement among raft nodes before linearized reading' (duration: 100.87828ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:29.394826Z","caller":"traceutil/trace.go:171","msg":"trace[1475998157] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"106.585395ms","start":"2026-07-09T16:48:29.288208Z","end":"2026-07-09T16:48:29.394794Z","steps":["trace[1475998157] 'process raft request' (duration: 105.944691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:31.553194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.513751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-09T16:48:31.553251Z","caller":"traceutil/trace.go:171","msg":"trace[667891145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4556; }","duration":"101.609073ms","start":"2026-07-09T16:48:31.451628Z","end":"2026-07-09T16:48:31.553237Z","steps":["trace[667891145] 'agreement among raft nodes before linearized reading' (duration: 74.912781ms)","trace[667891145] 'range keys from in-memory index tree' (duration: 26.549918ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:48:33.938197Z","caller":"traceutil/trace.go:171","msg":"trace[139046547] linearizableReadLoop","detail":"{readStateIndex:4812; appliedIndex:4811; }","duration":"198.030245ms","start":"2026-07-09T16:48:33.740143Z","end":"2026-07-09T16:48:33.938173Z","steps":["trace[139046547] 'read index received' (duration: 197.872812ms)","trace[139046547] 'applied index is now lower than readState.Index' (duration: 156.343µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:48:33.938254Z","caller":"traceutil/trace.go:171","msg":"trace[818263747] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"241.285193ms","start":"2026-07-09T16:48:33.696933Z","end":"2026-07-09T16:48:33.938218Z","steps":["trace[818263747] 'process raft request' (duration: 241.094029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:33.93837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.204749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:48:33.938405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.529337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-09T16:48:33.938427Z","caller":"traceutil/trace.go:171","msg":"trace[711448052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4625; }","duration":"198.319731ms","start":"2026-07-09T16:48:33.740095Z","end":"2026-07-09T16:48:33.938414Z","steps":["trace[711448052] 'agreement among raft nodes before linearized reading' (duration: 198.168668ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:33.938438Z","caller":"traceutil/trace.go:171","msg":"trace[2021330289] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4625; }","duration":"189.607019ms","start":"2026-07-09T16:48:33.748824Z","end":"2026-07-09T16:48:33.938431Z","steps":["trace[2021330289] 'agreement among raft nodes before linearized reading' (duration: 189.479296ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:38.663612Z","caller":"traceutil/trace.go:171","msg":"trace[370508386] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"289.248554ms","start":"2026-07-09T16:48:38.37434Z","end":"2026-07-09T16:48:38.663588Z","steps":["trace[370508386] 'process raft request' (duration: 289.077491ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:38.794461Z","caller":"traceutil/trace.go:171","msg":"trace[905252525] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"360.788433ms","start":"2026-07-09T16:48:38.433631Z","end":"2026-07-09T16:48:38.794419Z","steps":["trace[905252525] 'process raft request' (duration: 360.549678ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:38.794536Z","caller":"traceutil/trace.go:171","msg":"trace[569664306] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"270.168203ms","start":"2026-07-09T16:48:38.524351Z","end":"2026-07-09T16:48:38.794519Z","steps":["trace[569664306] 'process raft request' (duration: 269.981419ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:38.794561Z","caller":"traceutil/trace.go:171","msg":"trace[124012032] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"224.281179ms","start":"2026-07-09T16:48:38.570266Z","end":"2026-07-09T16:48:38.794547Z","steps":["trace[124012032] 'process raft request' (duration: 224.130326ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:38.7945Z","caller":"traceutil/trace.go:171","msg":"trace[1684885751] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"142.219158ms","start":"2026-07-09T16:48:38.652257Z","end":"2026-07-09T16:48:38.794476Z","steps":["trace[1684885751] 'process raft request' (duration: 142.178177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:38.794753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:48:38.433598Z","time spent":"360.959385ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:48:39.347553Z","caller":"traceutil/trace.go:171","msg":"trace[1374367888] linearizableReadLoop","detail":"{readStateIndex:4910; appliedIndex:4909; }","duration":"283.453033ms","start":"2026-07-09T16:48:39.064077Z","end":"2026-07-09T16:48:39.34753Z","steps":["trace[1374367888] 'read index received' (duration: 283.267668ms)","trace[1374367888] 'applied index is now lower than readState.Index' (duration: 184.105µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:48:39.347673Z","caller":"traceutil/trace.go:171","msg":"trace[149867906] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"302.488442ms","start":"2026-07-09T16:48:39.045174Z","end":"2026-07-09T16:48:39.347663Z","steps":["trace[149867906] 'process raft request' (duration: 302.206267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:39.347778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:48:39.045151Z","time spent":"302.549064ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:48:39.348282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.536017ms","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-07-09T16:48:39.34843Z","caller":"traceutil/trace.go:171","msg":"trace[1062304547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4721; }","duration":"197.713141ms","start":"2026-07-09T16:48:39.150659Z","end":"2026-07-09T16:48:39.348373Z","steps":["trace[1062304547] 'agreement among raft nodes before linearized reading' (duration: 197.378484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:39.348382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.274031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-09T16:48:39.3485Z","caller":"traceutil/trace.go:171","msg":"trace[861613785] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4721; }","duration":"284.442974ms","start":"2026-07-09T16:48:39.064035Z","end":"2026-07-09T16:48:39.348478Z","steps":["trace[861613785] 'agreement among raft nodes before linearized reading' (duration: 284.029435ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:40.90907Z","caller":"traceutil/trace.go:171","msg":"trace[775700210] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"108.362489ms","start":"2026-07-09T16:48:40.800681Z","end":"2026-07-09T16:48:40.909043Z","steps":["trace[775700210] 'process raft request' (duration: 108.158205ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:40.910331Z","caller":"traceutil/trace.go:171","msg":"trace[1238878602] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"108.85394ms","start":"2026-07-09T16:48:40.801458Z","end":"2026-07-09T16:48:40.910312Z","steps":["trace[1238878602] 'process raft request' (duration: 108.762478ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:42.536434Z","caller":"traceutil/trace.go:171","msg":"trace[527825168] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"118.701974ms","start":"2026-07-09T16:48:42.417714Z","end":"2026-07-09T16:48:42.536416Z","steps":["trace[527825168] 'process raft request' (duration: 118.55988ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:45.579377Z","caller":"traceutil/trace.go:171","msg":"trace[642187631] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4810; }","duration":"102.619155ms","start":"2026-07-09T16:48:45.476712Z","end":"2026-07-09T16:48:45.579331Z","steps":["trace[642187631] 'process raft request' (duration: 101.843207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:52.746243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.504619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-07-09T16:48:52.746326Z","caller":"traceutil/trace.go:171","msg":"trace[444509545] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4883; }","duration":"259.621712ms","start":"2026-07-09T16:48:52.486686Z","end":"2026-07-09T16:48:52.746308Z","steps":["trace[444509545] 'range keys from in-memory index tree' (duration: 259.358596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:52.746484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.307302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-09T16:48:52.746532Z","caller":"traceutil/trace.go:171","msg":"trace[281628254] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4883; }","duration":"231.381554ms","start":"2026-07-09T16:48:52.515137Z","end":"2026-07-09T16:48:52.746518Z","steps":["trace[281628254] 'range keys from in-memory index tree' (duration: 231.20199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:52.7471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.597449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-07-09T16:48:52.747188Z","caller":"traceutil/trace.go:171","msg":"trace[1449926075] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4883; }","duration":"316.700481ms","start":"2026-07-09T16:48:52.430454Z","end":"2026-07-09T16:48:52.747155Z","steps":["trace[1449926075] 'range keys from in-memory index tree' (duration: 316.444135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:52.747261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:48:52.430435Z","time spent":"316.794883ms","remote":"127.0.0.1:60388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9663,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-07-09T16:48:53.285864Z","caller":"traceutil/trace.go:171","msg":"trace[1199655704] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"532.298995ms","start":"2026-07-09T16:48:52.753505Z","end":"2026-07-09T16:48:53.285804Z","steps":["trace[1199655704] 'process raft request' (duration: 532.115891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:53.286048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:48:52.753489Z","time spent":"532.464019ms","remote":"127.0.0.1:60040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:48:53.328027Z","caller":"traceutil/trace.go:171","msg":"trace[1774751759] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"571.334649ms","start":"2026-07-09T16:48:52.756653Z","end":"2026-07-09T16:48:53.327987Z","steps":["trace[1774751759] 'process raft request' (duration: 570.955551ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:53.328312Z","caller":"traceutil/trace.go:171","msg":"trace[1161701393] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"373.46975ms","start":"2026-07-09T16:48:52.954821Z","end":"2026-07-09T16:48:53.328291Z","steps":["trace[1161701393] 'process raft request' (duration: 373.00549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:53.328455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:48:52.954787Z","time spent":"373.578502ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:48:53.32838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:48:52.756641Z","time spent":"571.507333ms","remote":"127.0.0.1:60154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:48:53.328531Z","caller":"traceutil/trace.go:171","msg":"trace[1956704105] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"371.779212ms","start":"2026-07-09T16:48:52.95673Z","end":"2026-07-09T16:48:53.328509Z","steps":["trace[1956704105] 'process raft request' (duration: 371.521567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:53.328634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:48:52.956712Z","time spent":"371.874484ms","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:48:53.328782Z","caller":"traceutil/trace.go:171","msg":"trace[1811769133] linearizableReadLoop","detail":"{readStateIndex:5081; appliedIndex:5079; }","duration":"527.862988ms","start":"2026-07-09T16:48:52.800611Z","end":"2026-07-09T16:48:53.328474Z","steps":["trace[1811769133] 'read index received' (duration: 485.160053ms)","trace[1811769133] 'applied index is now lower than readState.Index' (duration: 42.701505ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:48:53.328847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.216945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:48:53.328922Z","caller":"traceutil/trace.go:171","msg":"trace[1635525660] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4887; }","duration":"528.348748ms","start":"2026-07-09T16:48:52.800552Z","end":"2026-07-09T16:48:53.328901Z","steps":["trace[1635525660] 'agreement among raft nodes before linearized reading' (duration: 528.164454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:53.328979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:48:52.800535Z","time spent":"528.4278ms","remote":"127.0.0.1:60678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:48:53.45249Z","caller":"traceutil/trace.go:171","msg":"trace[159475318] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"117.0493ms","start":"2026-07-09T16:48:53.335414Z","end":"2026-07-09T16:48:53.452463Z","steps":["trace[159475318] 'process raft request' (duration: 67.22259ms)","trace[159475318] 'compare' (duration: 49.631366ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:48:53.476208Z","caller":"traceutil/trace.go:171","msg":"trace[1427111463] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"140.310529ms","start":"2026-07-09T16:48:53.335873Z","end":"2026-07-09T16:48:53.476184Z","steps":["trace[1427111463] 'process raft request' (duration: 140.147836ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:53.476259Z","caller":"traceutil/trace.go:171","msg":"trace[406339071] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"134.495551ms","start":"2026-07-09T16:48:53.341734Z","end":"2026-07-09T16:48:53.476229Z","steps":["trace[406339071] 'process raft request' (duration: 134.386969ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:53.476302Z","caller":"traceutil/trace.go:171","msg":"trace[1093197682] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"139.624493ms","start":"2026-07-09T16:48:53.336655Z","end":"2026-07-09T16:48:53.476279Z","steps":["trace[1093197682] 'process raft request' (duration: 139.416358ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:53.476137Z","caller":"traceutil/trace.go:171","msg":"trace[1794653725] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"140.41494ms","start":"2026-07-09T16:48:53.335683Z","end":"2026-07-09T16:48:53.476098Z","steps":["trace[1794653725] 'process raft request' (duration: 140.166745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:53.805062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.038177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-09T16:48:53.805132Z","caller":"traceutil/trace.go:171","msg":"trace[1086186553] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4898; }","duration":"244.14154ms","start":"2026-07-09T16:48:53.560974Z","end":"2026-07-09T16:48:53.805115Z","steps":["trace[1086186553] 'range keys from in-memory index tree' (duration: 243.904865ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:53.805123Z","caller":"traceutil/trace.go:171","msg":"trace[1412889971] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"242.878532ms","start":"2026-07-09T16:48:53.562214Z","end":"2026-07-09T16:48:53.805092Z","steps":["trace[1412889971] 'process raft request' (duration: 242.32376ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:53.875917Z","caller":"traceutil/trace.go:171","msg":"trace[1555859938] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"239.961698ms","start":"2026-07-09T16:48:53.635926Z","end":"2026-07-09T16:48:53.875888Z","steps":["trace[1555859938] 'process raft request' (duration: 239.290072ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:53.876538Z","caller":"traceutil/trace.go:171","msg":"trace[1710732582] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"221.056455ms","start":"2026-07-09T16:48:53.655468Z","end":"2026-07-09T16:48:53.876525Z","steps":["trace[1710732582] 'process raft request' (duration: 220.056632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:53.877412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.860458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T16:48:53.877471Z","caller":"traceutil/trace.go:171","msg":"trace[1135867784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4901; }","duration":"213.962149ms","start":"2026-07-09T16:48:53.663495Z","end":"2026-07-09T16:48:53.877457Z","steps":["trace[1135867784] 'agreement among raft nodes before linearized reading' (duration: 213.796276ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:53.877246Z","caller":"traceutil/trace.go:171","msg":"trace[1875485532] linearizableReadLoop","detail":"{readStateIndex:5097; appliedIndex:5094; }","duration":"212.789194ms","start":"2026-07-09T16:48:53.66353Z","end":"2026-07-09T16:48:53.876319Z","steps":["trace[1875485532] 'read index received' (duration: 141.395643ms)","trace[1875485532] 'applied index is now lower than readState.Index' (duration: 71.392201ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:48:53.896977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.859701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-09T16:48:53.897055Z","caller":"traceutil/trace.go:171","msg":"trace[1841768605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4902; }","duration":"226.996565ms","start":"2026-07-09T16:48:53.670042Z","end":"2026-07-09T16:48:53.897039Z","steps":["trace[1841768605] 'agreement among raft nodes before linearized reading' (duration: 226.77899ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:54.039474Z","caller":"traceutil/trace.go:171","msg":"trace[505261982] linearizableReadLoop","detail":"{readStateIndex:5099; appliedIndex:5098; }","duration":"142.770451ms","start":"2026-07-09T16:48:53.896679Z","end":"2026-07-09T16:48:54.03945Z","steps":["trace[505261982] 'read index received' (duration: 54.905451ms)","trace[505261982] 'applied index is now lower than readState.Index' (duration: 87.8638ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:48:54.039502Z","caller":"traceutil/trace.go:171","msg":"trace[1081985496] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"157.606247ms","start":"2026-07-09T16:48:53.881854Z","end":"2026-07-09T16:48:54.03946Z","steps":["trace[1081985496] 'process raft request' (duration: 69.714884ms)","trace[1081985496] 'compare' (duration: 87.695709ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:48:54.03958Z","caller":"traceutil/trace.go:171","msg":"trace[1768445550] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"138.560301ms","start":"2026-07-09T16:48:53.901008Z","end":"2026-07-09T16:48:54.039568Z","steps":["trace[1768445550] 'process raft request' (duration: 138.402477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:48:54.039701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.379638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-dl4bk.18c0ad0a8feb617f\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-07-09T16:48:54.039743Z","caller":"traceutil/trace.go:171","msg":"trace[2069980703] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-dl4bk.18c0ad0a8feb617f; range_end:; response_count:1; response_revision:4904; }","duration":"160.4844ms","start":"2026-07-09T16:48:53.879247Z","end":"2026-07-09T16:48:54.039731Z","steps":["trace[2069980703] 'agreement among raft nodes before linearized reading' (duration: 160.293545ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:54.040347Z","caller":"traceutil/trace.go:171","msg":"trace[1163333754] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"138.678983ms","start":"2026-07-09T16:48:53.901657Z","end":"2026-07-09T16:48:54.040336Z","steps":["trace[1163333754] 'process raft request' (duration: 137.875975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:22.138928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802821473623198257,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:49:24.155229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802821473623198257,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:49:24.259158Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.65312531s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:49:24.380963Z","caller":"traceutil/trace.go:171","msg":"trace[1251878579] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"2.764605409s","start":"2026-07-09T16:49:21.616333Z","end":"2026-07-09T16:49:24.380938Z","steps":["trace[1251878579] 'process raft request' (duration: 2.764508847s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.61631Z","time spent":"2.764731252s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:49:24.381162Z","caller":"traceutil/trace.go:171","msg":"trace[950899729] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"2.398428495s","start":"2026-07-09T16:49:21.982725Z","end":"2026-07-09T16:49:24.381154Z","steps":["trace[950899729] 'process raft request' (duration: 2.39817103s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.381193Z","caller":"traceutil/trace.go:171","msg":"trace[1940185638] linearizableReadLoop","detail":"{readStateIndex:5397; appliedIndex:5392; }","duration":"2.743174117s","start":"2026-07-09T16:49:21.638002Z","end":"2026-07-09T16:49:24.381176Z","steps":["trace[1940185638] 'read index received' (duration: 2.621526229s)","trace[1940185638] 'applied index is now lower than readState.Index' (duration: 121.647218ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:49:24.381263Z","caller":"traceutil/trace.go:171","msg":"trace[48721595] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"205.982807ms","start":"2026-07-09T16:49:24.17526Z","end":"2026-07-09T16:49:24.381243Z","steps":["trace[48721595] 'process raft request' (duration: 205.892035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.982699Z","time spent":"2.398500096s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:49:24.381273Z","caller":"traceutil/trace.go:171","msg":"trace[2091447329] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"2.397519686s","start":"2026-07-09T16:49:21.983702Z","end":"2026-07-09T16:49:24.381222Z","steps":["trace[2091447329] 'process raft request' (duration: 2.397388962s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.380972Z","caller":"traceutil/trace.go:171","msg":"trace[2063939312] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"2.766476549s","start":"2026-07-09T16:49:21.614455Z","end":"2026-07-09T16:49:24.380931Z","steps":["trace[2063939312] 'process raft request' (duration: 2.766186893s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.548602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:24.381402Z","caller":"traceutil/trace.go:171","msg":"trace[1441017757] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5195; }","duration":"208.585823ms","start":"2026-07-09T16:49:24.172809Z","end":"2026-07-09T16:49:24.381395Z","steps":["trace[1441017757] 'agreement among raft nodes before linearized reading' (duration: 208.562973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.614434Z","time spent":"2.76693901s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:49:24.381434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.983691Z","time spent":"2.397659488s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:49:24.381471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.705742791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:49:24.381495Z","caller":"traceutil/trace.go:171","msg":"trace[113986303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5195; }","duration":"2.705798021s","start":"2026-07-09T16:49:21.675691Z","end":"2026-07-09T16:49:24.381489Z","steps":["trace[113986303] 'agreement among raft nodes before linearized reading' (duration: 2.705683809s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.675673Z","time spent":"2.705836231s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:49:24.381521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.096575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:49:24.381547Z","caller":"traceutil/trace.go:171","msg":"trace[803080852] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:5195; }","duration":"224.137947ms","start":"2026-07-09T16:49:24.157399Z","end":"2026-07-09T16:49:24.381537Z","steps":["trace[803080852] 'agreement among raft nodes before linearized reading' (duration: 224.091576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.743335131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-09T16:49:24.381613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9837498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:49:24.381615Z","caller":"traceutil/trace.go:171","msg":"trace[378286045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5195; }","duration":"2.743632386s","start":"2026-07-09T16:49:21.637966Z","end":"2026-07-09T16:49:24.381598Z","steps":["trace[378286045] 'agreement among raft nodes before linearized reading' (duration: 2.743293139s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.381629Z","caller":"traceutil/trace.go:171","msg":"trace[1821622450] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5195; }","duration":"1.98380361s","start":"2026-07-09T16:49:22.397821Z","end":"2026-07-09T16:49:24.381625Z","steps":["trace[1821622450] 'agreement among raft nodes before linearized reading' (duration: 1.983765259s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.175898429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:49:24.381652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:21.637946Z","time spent":"2.743694988s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T16:49:24.381664Z","caller":"traceutil/trace.go:171","msg":"trace[1946697602] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5195; }","duration":"2.17592268s","start":"2026-07-09T16:49:22.205736Z","end":"2026-07-09T16:49:24.381659Z","steps":["trace[1946697602] 'agreement among raft nodes before linearized reading' (duration: 2.175888049s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.866049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:49:24.381682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.205655Z","time spent":"2.176021752s","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:49:24.381694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.182950892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:49:24.381712Z","caller":"traceutil/trace.go:171","msg":"trace[1215436445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5195; }","duration":"2.183000332s","start":"2026-07-09T16:49:22.198706Z","end":"2026-07-09T16:49:24.381707Z","steps":["trace[1215436445] 'agreement among raft nodes before linearized reading' (duration: 2.182949761s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.558591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:49:24.381729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.198669Z","time spent":"2.183056414s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T16:49:24.381739Z","caller":"traceutil/trace.go:171","msg":"trace[227490312] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5195; }","duration":"214.583871ms","start":"2026-07-09T16:49:24.16715Z","end":"2026-07-09T16:49:24.381734Z","steps":["trace[227490312] 'agreement among raft nodes before linearized reading' (duration: 214.556301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.783052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:49:24.381775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.030153659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:24.381794Z","caller":"traceutil/trace.go:171","msg":"trace[1561268582] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5195; }","duration":"2.030280712s","start":"2026-07-09T16:49:22.351507Z","end":"2026-07-09T16:49:24.381788Z","steps":["trace[1561268582] 'agreement among raft nodes before linearized reading' (duration: 2.030251141s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.183433612s","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-07-09T16:49:24.381799Z","caller":"traceutil/trace.go:171","msg":"trace[263730526] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5195; }","duration":"226.023848ms","start":"2026-07-09T16:49:24.155761Z","end":"2026-07-09T16:49:24.381785Z","steps":["trace[263730526] 'agreement among raft nodes before linearized reading' (duration: 225.795413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.351469Z","time spent":"2.030337893s","remote":"127.0.0.1:46306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:49:24.381873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.729445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-07-09T16:49:24.381893Z","caller":"traceutil/trace.go:171","msg":"trace[1870457612] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5195; }","duration":"214.783367ms","start":"2026-07-09T16:49:24.167104Z","end":"2026-07-09T16:49:24.381888Z","steps":["trace[1870457612] 'agreement among raft nodes before linearized reading' (duration: 214.720215ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.381696Z","caller":"traceutil/trace.go:171","msg":"trace[1622338622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5195; }","duration":"208.916811ms","start":"2026-07-09T16:49:24.172773Z","end":"2026-07-09T16:49:24.38169Z","steps":["trace[1622338622] 'agreement among raft nodes before linearized reading' (duration: 208.87866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.381646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.397803Z","time spent":"1.983838851s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:49:24.382004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.182414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:49:24.382024Z","caller":"traceutil/trace.go:171","msg":"trace[716340294] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5195; }","duration":"222.223917ms","start":"2026-07-09T16:49:24.159794Z","end":"2026-07-09T16:49:24.382018Z","steps":["trace[716340294] 'agreement among raft nodes before linearized reading' (duration: 222.182956ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.381823Z","caller":"traceutil/trace.go:171","msg":"trace[1388923602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5195; }","duration":"2.183505603s","start":"2026-07-09T16:49:22.198313Z","end":"2026-07-09T16:49:24.381819Z","steps":["trace[1388923602] 'agreement among raft nodes before linearized reading' (duration: 2.183455813s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.382055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.198278Z","time spent":"2.183762559s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:49:24.381935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.174483818s","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-07-09T16:49:24.382101Z","caller":"traceutil/trace.go:171","msg":"trace[803325758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5195; }","duration":"2.174671802s","start":"2026-07-09T16:49:22.207424Z","end":"2026-07-09T16:49:24.382096Z","steps":["trace[803325758] 'agreement among raft nodes before linearized reading' (duration: 2.174475378s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.382113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:49:22.207412Z","time spent":"2.174698044s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:49:24.382114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.666848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:49:24.382134Z","caller":"traceutil/trace.go:171","msg":"trace[342487729] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:5195; }","duration":"224.697988ms","start":"2026-07-09T16:49:24.15743Z","end":"2026-07-09T16:49:24.382128Z","steps":["trace[342487729] 'agreement among raft nodes before linearized reading' (duration: 224.613536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:24.382209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.407712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:49:24.382204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.373601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:24.382224Z","caller":"traceutil/trace.go:171","msg":"trace[1115290846] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5195; }","duration":"223.439662ms","start":"2026-07-09T16:49:24.15878Z","end":"2026-07-09T16:49:24.38222Z","steps":["trace[1115290846] 'agreement among raft nodes before linearized reading' (duration: 223.408032ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.38224Z","caller":"traceutil/trace.go:171","msg":"trace[1585939684] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:5195; }","duration":"223.420761ms","start":"2026-07-09T16:49:24.158809Z","end":"2026-07-09T16:49:24.382229Z","steps":["trace[1585939684] 'agreement among raft nodes before linearized reading' (duration: 223.353429ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.503054Z","caller":"traceutil/trace.go:171","msg":"trace[1117849406] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"117.181134ms","start":"2026-07-09T16:49:24.385841Z","end":"2026-07-09T16:49:24.503022Z","steps":["trace[1117849406] 'process raft request' (duration: 87.14431ms)","trace[1117849406] 'compare' (duration: 29.767208ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:49:24.503257Z","caller":"traceutil/trace.go:171","msg":"trace[698414228] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"114.977145ms","start":"2026-07-09T16:49:24.388242Z","end":"2026-07-09T16:49:24.503219Z","steps":["trace[698414228] 'process raft request' (duration: 114.873483ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.503504Z","caller":"traceutil/trace.go:171","msg":"trace[1717080035] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"115.720583ms","start":"2026-07-09T16:49:24.387769Z","end":"2026-07-09T16:49:24.50349Z","steps":["trace[1717080035] 'process raft request' (duration: 115.205471ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:24.50357Z","caller":"traceutil/trace.go:171","msg":"trace[1549801595] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"115.374704ms","start":"2026-07-09T16:49:24.388142Z","end":"2026-07-09T16:49:24.503517Z","steps":["trace[1549801595] 'process raft request' (duration: 114.905424ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:36.573604Z","caller":"traceutil/trace.go:171","msg":"trace[1946017974] linearizableReadLoop","detail":"{readStateIndex:5469; appliedIndex:5466; }","duration":"121.752327ms","start":"2026-07-09T16:49:36.451835Z","end":"2026-07-09T16:49:36.573587Z","steps":["trace[1946017974] 'read index received' (duration: 79.840329ms)","trace[1946017974] 'applied index is now lower than readState.Index' (duration: 41.911338ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:49:36.573703Z","caller":"traceutil/trace.go:171","msg":"trace[1785674101] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"136.707157ms","start":"2026-07-09T16:49:36.436986Z","end":"2026-07-09T16:49:36.573693Z","steps":["trace[1785674101] 'process raft request' (duration: 136.568024ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:36.573767Z","caller":"traceutil/trace.go:171","msg":"trace[2138532012] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"136.945973ms","start":"2026-07-09T16:49:36.436777Z","end":"2026-07-09T16:49:36.573723Z","steps":["trace[2138532012] 'process raft request' (duration: 136.733119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:36.574232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.917005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:49:36.574269Z","caller":"traceutil/trace.go:171","msg":"trace[258969424] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5263; }","duration":"138.997967ms","start":"2026-07-09T16:49:36.435263Z","end":"2026-07-09T16:49:36.574261Z","steps":["trace[258969424] 'agreement among raft nodes before linearized reading' (duration: 138.931125ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:36.573998Z","caller":"traceutil/trace.go:171","msg":"trace[212171647] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"137.240658ms","start":"2026-07-09T16:49:36.436748Z","end":"2026-07-09T16:49:36.573989Z","steps":["trace[212171647] 'process raft request' (duration: 95.043685ms)","trace[212171647] 'compare' (duration: 41.623241ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:50:03.28289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2825} {"level":"info","ts":"2026-07-09T16:50:03.368835Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2825,"took":"84.809908ms","hash":752884280,"current-db-size-bytes":52920320,"current-db-size":"53 MB","current-db-size-in-use-bytes":29044736,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-09T16:50:03.368934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":752884280,"revision":2825,"compact-revision":-1} {"level":"info","ts":"2026-07-09T16:52:25.496649Z","caller":"traceutil/trace.go:171","msg":"trace[1411085545] transaction","detail":"{read_only:false; response_revision:6458; number_of_response:1; }","duration":"151.804813ms","start":"2026-07-09T16:52:25.344787Z","end":"2026-07-09T16:52:25.496591Z","steps":["trace[1411085545] 'process raft request' (duration: 81.108018ms)","trace[1411085545] 'compare' (duration: 70.358348ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:52:57.835366Z","caller":"traceutil/trace.go:171","msg":"trace[735432771] transaction","detail":"{read_only:false; response_revision:6680; number_of_response:1; }","duration":"149.511859ms","start":"2026-07-09T16:52:57.685831Z","end":"2026-07-09T16:52:57.835343Z","steps":["trace[735432771] 'process raft request' (duration: 149.228839ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:53:20.212512Z","caller":"traceutil/trace.go:171","msg":"trace[1308092883] transaction","detail":"{read_only:false; response_revision:6802; number_of_response:1; }","duration":"136.562135ms","start":"2026-07-09T16:53:20.075923Z","end":"2026-07-09T16:53:20.212485Z","steps":["trace[1308092883] 'process raft request' (duration: 73.941872ms)","trace[1308092883] 'compare' (duration: 62.235298ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:54:12.578274Z","caller":"traceutil/trace.go:171","msg":"trace[1425037191] transaction","detail":"{read_only:false; response_revision:7082; number_of_response:1; }","duration":"116.373796ms","start":"2026-07-09T16:54:12.461875Z","end":"2026-07-09T16:54:12.578249Z","steps":["trace[1425037191] 'process raft request' (duration: 63.479064ms)","trace[1425037191] 'compare' (duration: 52.458997ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:54:12.578299Z","caller":"traceutil/trace.go:171","msg":"trace[1662022579] transaction","detail":"{read_only:false; response_revision:7083; number_of_response:1; }","duration":"116.141968ms","start":"2026-07-09T16:54:12.462119Z","end":"2026-07-09T16:54:12.578261Z","steps":["trace[1662022579] 'process raft request' (duration: 116.032275ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:55:03.289182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5481} {"level":"info","ts":"2026-07-09T16:55:03.374644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5481,"took":"83.143879ms","hash":1262396312,"current-db-size-bytes":52920320,"current-db-size":"53 MB","current-db-size-in-use-bytes":18374656,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-09T16:55:03.374822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1262396312,"revision":5481,"compact-revision":2825}