{"level":"warn","ts":"2026-05-29T21:53:50.948126Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:53:50.94823Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.91: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.91:2380","--initial-cluster=instance=https://199.19.213.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.91:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T21:53:50.948325Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:53:50.948346Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-29T21:53:50.948373Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T21:53:50.949363Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"]} {"level":"info","ts":"2026-05-29T21:53:50.949481Z","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.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.91:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T21:53:51.054657Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"104.899352ms"} {"level":"info","ts":"2026-05-29T21:53:51.068719Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2c24222821500990","cluster-id":"f38faa6ba493daad"} {"level":"info","ts":"2026-05-29T21:53:51.06882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T21:53:51.068856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 0"} {"level":"info","ts":"2026-05-29T21:53:51.068876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2c24222821500990 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T21:53:51.068899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 1"} {"level":"info","ts":"2026-05-29T21:53:51.068947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"warn","ts":"2026-05-29T21:53:51.074921Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T21:53:51.076565Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T21:53:51.077764Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T21:53:51.08049Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2c24222821500990","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T21:53:51.080845Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:53:51.080931Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:53:51.08095Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:53:51.081273Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2c24222821500990","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T21:53:51.081876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"info","ts":"2026-05-29T21:53:51.082752Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","added-peer-id":"2c24222821500990","added-peer-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-29T21:53:51.085658Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T21:53:51.08585Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2c24222821500990","initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T21:53:51.085882Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T21:53:51.085974Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-29T21:53:51.085987Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-29T21:53:51.569334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T21:53:51.56939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T21:53:51.569431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgPreVoteResp from 2c24222821500990 at term 1"} {"level":"info","ts":"2026-05-29T21:53:51.56945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became candidate at term 2"} {"level":"info","ts":"2026-05-29T21:53:51.569461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgVoteResp from 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-29T21:53:51.569487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became leader at term 2"} {"level":"info","ts":"2026-05-29T21:53:51.569501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2c24222821500990 elected leader 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-29T21:53:51.571657Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2c24222821500990","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.91:2379]}","request-path":"/0/members/2c24222821500990/attributes","cluster-id":"f38faa6ba493daad","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T21:53:51.571759Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:53:51.572023Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:53:51.572435Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:53:51.572655Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T21:53:51.57269Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T21:53:51.575594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T21:53:51.584618Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.91:2379"} {"level":"info","ts":"2026-05-29T21:53:51.589837Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:53:51.590211Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:53:51.590343Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:54:27.438806Z","caller":"traceutil/trace.go:171","msg":"trace[1646695628] transaction","detail":"{read_only:false; number_of_response:1; response_revision:487; }","duration":"458.584974ms","start":"2026-05-29T21:54:26.980196Z","end":"2026-05-29T21:54:27.438781Z","steps":["trace[1646695628] 'process raft request' (duration: 458.354686ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:27.439122Z","caller":"traceutil/trace.go:171","msg":"trace[779598725] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"453.718327ms","start":"2026-05-29T21:54:26.98532Z","end":"2026-05-29T21:54:27.439039Z","steps":["trace[779598725] 'process raft request' (duration: 453.465568ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:27.439156Z","caller":"traceutil/trace.go:171","msg":"trace[403034051] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"437.229144ms","start":"2026-05-29T21:54:27.00191Z","end":"2026-05-29T21:54:27.439139Z","steps":["trace[403034051] 'process raft request' (duration: 437.183783ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:27.439264Z","caller":"traceutil/trace.go:171","msg":"trace[233595243] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"446.951549ms","start":"2026-05-29T21:54:26.992303Z","end":"2026-05-29T21:54:27.439254Z","steps":["trace[233595243] 'process raft request' (duration: 446.648989ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:27.439273Z","caller":"traceutil/trace.go:171","msg":"trace[160893541] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"457.136861ms","start":"2026-05-29T21:54:26.982122Z","end":"2026-05-29T21:54:27.439259Z","steps":["trace[160893541] 'process raft request' (duration: 456.548569ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:27.439307Z","caller":"traceutil/trace.go:171","msg":"trace[152062742] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"446.580326ms","start":"2026-05-29T21:54:26.992719Z","end":"2026-05-29T21:54:27.439299Z","steps":["trace[152062742] 'process raft request' (duration: 446.272655ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:27.439297Z","caller":"traceutil/trace.go:171","msg":"trace[143678801] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"447.51236ms","start":"2026-05-29T21:54:26.991726Z","end":"2026-05-29T21:54:27.439238Z","steps":["trace[143678801] 'process raft request' (duration: 447.175228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:27.439363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:26.992714Z","time spent":"446.612227ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:54:27.439331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:26.982114Z","time spent":"457.182963ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:54:27.439422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:26.98019Z","time spent":"458.711909ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:54:27.439444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.001901Z","time spent":"437.314767ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:54:27.439514Z","caller":"traceutil/trace.go:171","msg":"trace[832364982] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"456.349212ms","start":"2026-05-29T21:54:26.983129Z","end":"2026-05-29T21:54:27.439478Z","steps":["trace[832364982] 'process raft request' (duration: 455.615495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:27.439531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:26.99172Z","time spent":"447.687546ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:54:27.439558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:26.98312Z","time spent":"456.416345ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:54:27.439567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:26.992297Z","time spent":"446.985921ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:54:27.439569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:26.985313Z","time spent":"453.885002ms","remote":"127.0.0.1:42144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:54:27.43963Z","caller":"traceutil/trace.go:171","msg":"trace[753475389] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"454.198014ms","start":"2026-05-29T21:54:26.985418Z","end":"2026-05-29T21:54:27.439616Z","steps":["trace[753475389] 'process raft request' (duration: 453.394665ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:27.439658Z","caller":"traceutil/trace.go:171","msg":"trace[1216742253] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"452.209201ms","start":"2026-05-29T21:54:26.987435Z","end":"2026-05-29T21:54:27.439644Z","steps":["trace[1216742253] 'process raft request' (duration: 451.412182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:27.439709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:26.985412Z","time spent":"454.267446ms","remote":"127.0.0.1:42144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:54:27.439715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:26.987427Z","time spent":"452.255103ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:54:27.455712Z","caller":"traceutil/trace.go:171","msg":"trace[1675856602] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:503; }","duration":"250.104508ms","start":"2026-05-29T21:54:27.205578Z","end":"2026-05-29T21:54:27.455683Z","steps":["trace[1675856602] 'read index received' (duration: 233.102205ms)","trace[1675856602] 'applied index is now lower than readState.Index' (duration: 17.001653ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:27.455785Z","caller":"traceutil/trace.go:171","msg":"trace[290466346] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"431.05185ms","start":"2026-05-29T21:54:27.024726Z","end":"2026-05-29T21:54:27.455778Z","steps":["trace[290466346] 'process raft request' (duration: 430.77876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:27.455842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.024695Z","time spent":"431.106892ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:54:27.45585Z","caller":"traceutil/trace.go:171","msg":"trace[1294611996] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"316.034109ms","start":"2026-05-29T21:54:27.139805Z","end":"2026-05-29T21:54:27.455839Z","steps":["trace[1294611996] 'process raft request' (duration: 315.831071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:27.455894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.139782Z","time spent":"316.09213ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:54:27.45602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.43659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-29T21:54:27.456066Z","caller":"traceutil/trace.go:171","msg":"trace[257113598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"250.521263ms","start":"2026-05-29T21:54:27.205536Z","end":"2026-05-29T21:54:27.456057Z","steps":["trace[257113598] 'agreement among raft nodes before linearized reading' (duration: 250.4329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:27.465417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.121176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:54:27.46547Z","caller":"traceutil/trace.go:171","msg":"trace[1262755748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"256.201908ms","start":"2026-05-29T21:54:27.209256Z","end":"2026-05-29T21:54:27.465458Z","steps":["trace[1262755748] 'agreement among raft nodes before linearized reading' (duration: 256.100164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:27.465576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.881024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:27.465598Z","caller":"traceutil/trace.go:171","msg":"trace[660118946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"248.958357ms","start":"2026-05-29T21:54:27.216634Z","end":"2026-05-29T21:54:27.465592Z","steps":["trace[660118946] 'agreement among raft nodes before linearized reading' (duration: 248.922706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:32.858771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.750272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:54:32.858944Z","caller":"traceutil/trace.go:171","msg":"trace[174563546] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"335.294348ms","start":"2026-05-29T21:54:32.523611Z","end":"2026-05-29T21:54:32.858906Z","steps":["trace[174563546] 'read index received' (duration: 160.105111ms)","trace[174563546] 'applied index is now lower than readState.Index' (duration: 175.187906ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:32.858995Z","caller":"traceutil/trace.go:171","msg":"trace[910626359] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"336.609883ms","start":"2026-05-29T21:54:32.522356Z","end":"2026-05-29T21:54:32.858966Z","steps":["trace[910626359] 'process raft request' (duration: 161.359704ms)","trace[910626359] 'compare' (duration: 174.523374ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:32.859119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.504444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:54:32.859133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:32.522342Z","time spent":"336.731096ms","remote":"127.0.0.1:41974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:54:32.859153Z","caller":"traceutil/trace.go:171","msg":"trace[168481] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:580; }","duration":"335.563087ms","start":"2026-05-29T21:54:32.52358Z","end":"2026-05-29T21:54:32.859143Z","steps":["trace[168481] 'agreement among raft nodes before linearized reading' (duration: 335.451853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:32.859156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.406481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:54:32.859214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:32.523571Z","time spent":"335.631649ms","remote":"127.0.0.1:37160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-29T21:54:32.859238Z","caller":"traceutil/trace.go:171","msg":"trace[1027543780] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:580; }","duration":"335.522905ms","start":"2026-05-29T21:54:32.52369Z","end":"2026-05-29T21:54:32.859213Z","steps":["trace[1027543780] 'agreement among raft nodes before linearized reading' (duration: 335.36297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:32.859285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:32.523686Z","time spent":"335.588337ms","remote":"127.0.0.1:37160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-05-29T21:54:33.70302Z","caller":"traceutil/trace.go:171","msg":"trace[496801368] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"155.779006ms","start":"2026-05-29T21:54:33.547228Z","end":"2026-05-29T21:54:33.703008Z","steps":["trace[496801368] 'process raft request' (duration: 155.6023ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:34.451298Z","caller":"traceutil/trace.go:171","msg":"trace[698318034] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"489.575167ms","start":"2026-05-29T21:54:33.961695Z","end":"2026-05-29T21:54:34.45127Z","steps":["trace[698318034] 'read index received' (duration: 489.236335ms)","trace[698318034] 'applied index is now lower than readState.Index' (duration: 337.202µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:34.451476Z","caller":"traceutil/trace.go:171","msg":"trace[1407768604] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"582.758776ms","start":"2026-05-29T21:54:33.868706Z","end":"2026-05-29T21:54:34.451465Z","steps":["trace[1407768604] 'process raft request' (duration: 582.27522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:34.451647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:33.868677Z","time spent":"582.86839ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:54:34.451963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.25101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gzvdt\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-29T21:54:34.45204Z","caller":"traceutil/trace.go:171","msg":"trace[1444150473] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gzvdt; range_end:; response_count:1; response_revision:591; }","duration":"490.401295ms","start":"2026-05-29T21:54:33.96162Z","end":"2026-05-29T21:54:34.452021Z","steps":["trace[1444150473] 'agreement among raft nodes before linearized reading' (duration: 489.853846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:34.452215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:33.961586Z","time spent":"490.613602ms","remote":"127.0.0.1:41782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-gzvdt\" "} {"level":"info","ts":"2026-05-29T21:54:34.458184Z","caller":"traceutil/trace.go:171","msg":"trace[1756404628] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"467.942703ms","start":"2026-05-29T21:54:33.9902Z","end":"2026-05-29T21:54:34.458143Z","steps":["trace[1756404628] 'process raft request' (duration: 467.765807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:34.458467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:33.990171Z","time spent":"468.16304ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:54:57.416804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.191115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:57.416945Z","caller":"traceutil/trace.go:171","msg":"trace[386755052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"200.392001ms","start":"2026-05-29T21:54:57.216527Z","end":"2026-05-29T21:54:57.416919Z","steps":["trace[386755052] 'range keys from in-memory index tree' (duration: 200.101102ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:57.50082Z","caller":"traceutil/trace.go:171","msg":"trace[623984751] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"170.106079ms","start":"2026-05-29T21:54:57.330683Z","end":"2026-05-29T21:54:57.500789Z","steps":["trace[623984751] 'process raft request' (duration: 169.890143ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.269476Z","caller":"traceutil/trace.go:171","msg":"trace[427039861] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"113.09036ms","start":"2026-05-29T21:55:46.156363Z","end":"2026-05-29T21:55:46.269454Z","steps":["trace[427039861] 'process raft request' (duration: 102.860136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:19.894845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.784241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:56:19.894976Z","caller":"traceutil/trace.go:171","msg":"trace[963515257] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1880; }","duration":"211.956874ms","start":"2026-05-29T21:56:19.682995Z","end":"2026-05-29T21:56:19.894952Z","steps":["trace[963515257] 'count revisions from in-memory index tree' (duration: 211.721091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:19.895011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.68834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-29T21:56:19.895103Z","caller":"traceutil/trace.go:171","msg":"trace[1023789998] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1880; }","duration":"217.825342ms","start":"2026-05-29T21:56:19.677256Z","end":"2026-05-29T21:56:19.895082Z","steps":["trace[1023789998] 'range keys from in-memory index tree' (duration: 217.510557ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:19.918578Z","caller":"traceutil/trace.go:171","msg":"trace[851075940] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"171.303735ms","start":"2026-05-29T21:56:19.747248Z","end":"2026-05-29T21:56:19.918552Z","steps":["trace[851075940] 'read index received' (duration: 171.145773ms)","trace[851075940] 'applied index is now lower than readState.Index' (duration: 156.912µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:56:19.918626Z","caller":"traceutil/trace.go:171","msg":"trace[557493482] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"199.984574ms","start":"2026-05-29T21:56:19.718617Z","end":"2026-05-29T21:56:19.918601Z","steps":["trace[557493482] 'process raft request' (duration: 199.780841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:19.918735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.462748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:56:19.918781Z","caller":"traceutil/trace.go:171","msg":"trace[2002406927] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1881; }","duration":"171.556699ms","start":"2026-05-29T21:56:19.747211Z","end":"2026-05-29T21:56:19.918767Z","steps":["trace[2002406927] 'agreement among raft nodes before linearized reading' (duration: 171.466077ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:19.935762Z","caller":"traceutil/trace.go:171","msg":"trace[1768713895] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"137.490649ms","start":"2026-05-29T21:56:19.798255Z","end":"2026-05-29T21:56:19.935746Z","steps":["trace[1768713895] 'process raft request' (duration: 137.359257ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:36.548053Z","caller":"traceutil/trace.go:171","msg":"trace[685938405] linearizableReadLoop","detail":"{readStateIndex:2094; appliedIndex:2092; }","duration":"166.230928ms","start":"2026-05-29T21:56:36.3818Z","end":"2026-05-29T21:56:36.548031Z","steps":["trace[685938405] 'read index received' (duration: 164.070498ms)","trace[685938405] 'applied index is now lower than readState.Index' (duration: 2.1595ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:56:36.548117Z","caller":"traceutil/trace.go:171","msg":"trace[41970783] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"173.007643ms","start":"2026-05-29T21:56:36.375103Z","end":"2026-05-29T21:56:36.548111Z","steps":["trace[41970783] 'process raft request' (duration: 172.895532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:36.548288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.478941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:56:36.548314Z","caller":"traceutil/trace.go:171","msg":"trace[1711515898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2020; }","duration":"166.555693ms","start":"2026-05-29T21:56:36.381751Z","end":"2026-05-29T21:56:36.548307Z","steps":["trace[1711515898] 'agreement among raft nodes before linearized reading' (duration: 166.393421ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:36.548063Z","caller":"traceutil/trace.go:171","msg":"trace[911594507] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"176.064535ms","start":"2026-05-29T21:56:36.371979Z","end":"2026-05-29T21:56:36.548043Z","steps":["trace[911594507] 'process raft request' (duration: 173.884245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:36.54841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.490728ms","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-05-29T21:56:36.549195Z","caller":"traceutil/trace.go:171","msg":"trace[1940505410] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2020; }","duration":"156.30858ms","start":"2026-05-29T21:56:36.392872Z","end":"2026-05-29T21:56:36.54918Z","steps":["trace[1940505410] 'agreement among raft nodes before linearized reading' (duration: 155.447478ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:52.90249Z","caller":"traceutil/trace.go:171","msg":"trace[1857596305] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"135.144575ms","start":"2026-05-29T21:56:52.76732Z","end":"2026-05-29T21:56:52.902464Z","steps":["trace[1857596305] 'process raft request' (duration: 134.997123ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:52.968752Z","caller":"traceutil/trace.go:171","msg":"trace[1757343492] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2191; }","duration":"163.967824ms","start":"2026-05-29T21:56:52.80476Z","end":"2026-05-29T21:56:52.968728Z","steps":["trace[1757343492] 'read index received' (duration: 97.585608ms)","trace[1757343492] 'applied index is now lower than readState.Index' (duration: 66.381136ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:56:52.968828Z","caller":"traceutil/trace.go:171","msg":"trace[570142126] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"195.054215ms","start":"2026-05-29T21:56:52.773732Z","end":"2026-05-29T21:56:52.968786Z","steps":["trace[570142126] 'process raft request' (duration: 194.866923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:52.968941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.168428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-29T21:56:52.968986Z","caller":"traceutil/trace.go:171","msg":"trace[58702584] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2116; }","duration":"164.253139ms","start":"2026-05-29T21:56:52.804712Z","end":"2026-05-29T21:56:52.968965Z","steps":["trace[58702584] 'agreement among raft nodes before linearized reading' (duration: 164.163727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:52.969074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.703325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127378"} {"level":"info","ts":"2026-05-29T21:56:52.969126Z","caller":"traceutil/trace.go:171","msg":"trace[1520419306] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2116; }","duration":"161.778456ms","start":"2026-05-29T21:56:52.807335Z","end":"2026-05-29T21:56:52.969113Z","steps":["trace[1520419306] 'agreement among raft nodes before linearized reading' (duration: 161.568003ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:58.226769Z","caller":"traceutil/trace.go:171","msg":"trace[754466576] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"100.99646ms","start":"2026-05-29T21:56:58.125752Z","end":"2026-05-29T21:56:58.226748Z","steps":["trace[754466576] 'process raft request' (duration: 100.697996ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:58.942517Z","caller":"traceutil/trace.go:171","msg":"trace[1675631821] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"303.293334ms","start":"2026-05-29T21:56:58.639199Z","end":"2026-05-29T21:56:58.942493Z","steps":["trace[1675631821] 'read index received' (duration: 221.149708ms)","trace[1675631821] 'applied index is now lower than readState.Index' (duration: 82.142656ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:56:58.942866Z","caller":"traceutil/trace.go:171","msg":"trace[1068309237] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"316.547655ms","start":"2026-05-29T21:56:58.626088Z","end":"2026-05-29T21:56:58.942635Z","steps":["trace[1068309237] 'process raft request' (duration: 234.330009ms)","trace[1068309237] 'compare' (duration: 81.70425ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:56:58.943012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:58.626059Z","time spent":"316.850179ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:56:58.943207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.018818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-29T21:56:58.943237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.019183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T21:56:58.94327Z","caller":"traceutil/trace.go:171","msg":"trace[31301577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2143; }","duration":"299.090748ms","start":"2026-05-29T21:56:58.644163Z","end":"2026-05-29T21:56:58.943254Z","steps":["trace[31301577] 'agreement among raft nodes before linearized reading' (duration: 298.920576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:58.943288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.003584ms","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-05-29T21:56:58.94332Z","caller":"traceutil/trace.go:171","msg":"trace[1737810524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2143; }","duration":"304.139814ms","start":"2026-05-29T21:56:58.639162Z","end":"2026-05-29T21:56:58.943301Z","steps":["trace[1737810524] 'agreement among raft nodes before linearized reading' (duration: 303.943101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:58.9433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.036835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T21:56:58.943367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:58.639143Z","time spent":"304.215995ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T21:56:58.943383Z","caller":"traceutil/trace.go:171","msg":"trace[1616786444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2143; }","duration":"298.154967ms","start":"2026-05-29T21:56:58.645211Z","end":"2026-05-29T21:56:58.943366Z","steps":["trace[1616786444] 'agreement among raft nodes before linearized reading' (duration: 297.891913ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:58.943378Z","caller":"traceutil/trace.go:171","msg":"trace[80307334] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2143; }","duration":"244.143206ms","start":"2026-05-29T21:56:58.69921Z","end":"2026-05-29T21:56:58.943353Z","steps":["trace[80307334] 'agreement among raft nodes before linearized reading' (duration: 243.879302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:59.996356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.760915ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T21:58:23.075425Z","caller":"traceutil/trace.go:171","msg":"trace[1831902811] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"172.405431ms","start":"2026-05-29T21:58:22.902988Z","end":"2026-05-29T21:58:23.075393Z","steps":["trace[1831902811] 'process raft request' (duration: 172.237024ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:26.021325Z","caller":"traceutil/trace.go:171","msg":"trace[904296246] transaction","detail":"{read_only:false; response_revision:3837; number_of_response:1; }","duration":"101.266272ms","start":"2026-05-29T22:01:25.920032Z","end":"2026-05-29T22:01:26.021298Z","steps":["trace[904296246] 'process raft request' (duration: 101.132089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:09.963808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.226887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:02:09.963872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.121401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T22:02:09.963911Z","caller":"traceutil/trace.go:171","msg":"trace[1905214487] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4675; }","duration":"281.37505ms","start":"2026-05-29T22:02:09.682513Z","end":"2026-05-29T22:02:09.963888Z","steps":["trace[1905214487] 'count revisions from in-memory index tree' (duration: 281.100175ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:09.963963Z","caller":"traceutil/trace.go:171","msg":"trace[1414389285] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4675; }","duration":"413.300465ms","start":"2026-05-29T22:02:09.550642Z","end":"2026-05-29T22:02:09.963943Z","steps":["trace[1414389285] 'count revisions from in-memory index tree' (duration: 413.004049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:09.964016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:09.550614Z","time spent":"413.390517ms","remote":"127.0.0.1:34868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":8,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:02:09.978077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.013436ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T22:02:10.380469Z","caller":"traceutil/trace.go:171","msg":"trace[511528669] linearizableReadLoop","detail":"{readStateIndex:4848; appliedIndex:4847; }","duration":"140.323128ms","start":"2026-05-29T22:02:10.240051Z","end":"2026-05-29T22:02:10.380374Z","steps":["trace[511528669] 'read index received' (duration: 59.630965ms)","trace[511528669] 'applied index is now lower than readState.Index' (duration: 80.690193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:10.380697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.628234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-29T22:02:10.380824Z","caller":"traceutil/trace.go:171","msg":"trace[324169004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4677; }","duration":"140.798046ms","start":"2026-05-29T22:02:10.240013Z","end":"2026-05-29T22:02:10.380811Z","steps":["trace[324169004] 'agreement among raft nodes before linearized reading' (duration: 140.521781ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:13.353353Z","caller":"traceutil/trace.go:171","msg":"trace[1601685557] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"105.082031ms","start":"2026-05-29T22:02:13.248249Z","end":"2026-05-29T22:02:13.353331Z","steps":["trace[1601685557] 'process raft request' (duration: 104.952789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:22.84185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.314465ms","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-05-29T22:02:22.841945Z","caller":"traceutil/trace.go:171","msg":"trace[1890199968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4851; }","duration":"200.463898ms","start":"2026-05-29T22:02:22.641461Z","end":"2026-05-29T22:02:22.841925Z","steps":["trace[1890199968] 'range keys from in-memory index tree' (duration: 200.083071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:22.842116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.457321ms","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-05-29T22:02:22.842166Z","caller":"traceutil/trace.go:171","msg":"trace[1054720936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4851; }","duration":"127.536513ms","start":"2026-05-29T22:02:22.714613Z","end":"2026-05-29T22:02:22.84215Z","steps":["trace[1054720936] 'range keys from in-memory index tree' (duration: 127.322439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:22.842375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.628933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T22:02:22.84241Z","caller":"traceutil/trace.go:171","msg":"trace[59657342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4851; }","duration":"128.697754ms","start":"2026-05-29T22:02:22.713702Z","end":"2026-05-29T22:02:22.8424Z","steps":["trace[59657342] 'range keys from in-memory index tree' (duration: 128.500061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:22.841862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.309013ms","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-05-29T22:02:22.842475Z","caller":"traceutil/trace.go:171","msg":"trace[569656269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4851; }","duration":"163.934314ms","start":"2026-05-29T22:02:22.678526Z","end":"2026-05-29T22:02:22.84246Z","steps":["trace[569656269] 'range keys from in-memory index tree' (duration: 163.15702ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:23.542205Z","caller":"traceutil/trace.go:171","msg":"trace[316861451] linearizableReadLoop","detail":"{readStateIndex:5037; appliedIndex:5036; }","duration":"104.067457ms","start":"2026-05-29T22:02:23.438116Z","end":"2026-05-29T22:02:23.542183Z","steps":["trace[316861451] 'read index received' (duration: 13.093832ms)","trace[316861451] 'applied index is now lower than readState.Index' (duration: 90.972455ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:23.542542Z","caller":"traceutil/trace.go:171","msg":"trace[2102783898] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"131.605664ms","start":"2026-05-29T22:02:23.410888Z","end":"2026-05-29T22:02:23.542493Z","steps":["trace[2102783898] 'process raft request' (duration: 40.386114ms)","trace[2102783898] 'compare' (duration: 90.818772ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:23.542612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.475174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0a8265e7-79e2-4b5e-972c-460b6d637a0c\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-29T22:02:23.542669Z","caller":"traceutil/trace.go:171","msg":"trace[1425564936] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0a8265e7-79e2-4b5e-972c-460b6d637a0c; range_end:; response_count:1; response_revision:4860; }","duration":"104.565576ms","start":"2026-05-29T22:02:23.438087Z","end":"2026-05-29T22:02:23.542653Z","steps":["trace[1425564936] 'agreement among raft nodes before linearized reading' (duration: 104.362842ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:24.147745Z","caller":"traceutil/trace.go:171","msg":"trace[137380134] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"338.633611ms","start":"2026-05-29T22:02:23.809081Z","end":"2026-05-29T22:02:24.147714Z","steps":["trace[137380134] 'process raft request' (duration: 334.530736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:24.147923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:23.80906Z","time spent":"338.773144ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10926,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:44.538135Z","caller":"traceutil/trace.go:171","msg":"trace[1120994255] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5178; }","duration":"105.192479ms","start":"2026-05-29T22:02:44.432916Z","end":"2026-05-29T22:02:44.538109Z","steps":["trace[1120994255] 'read index received' (duration: 39.063944ms)","trace[1120994255] 'applied index is now lower than readState.Index' (duration: 66.127155ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:44.538781Z","caller":"traceutil/trace.go:171","msg":"trace[686223957] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"118.330093ms","start":"2026-05-29T22:02:44.420411Z","end":"2026-05-29T22:02:44.538741Z","steps":["trace[686223957] 'process raft request' (duration: 51.635418ms)","trace[686223957] 'compare' (duration: 65.874431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:44.539171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.226667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-29T22:02:44.539206Z","caller":"traceutil/trace.go:171","msg":"trace[1802703077] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:4999; }","duration":"106.326839ms","start":"2026-05-29T22:02:44.43287Z","end":"2026-05-29T22:02:44.539197Z","steps":["trace[1802703077] 'agreement among raft nodes before linearized reading' (duration: 105.414013ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:51.777165Z","caller":"traceutil/trace.go:171","msg":"trace[1928620071] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"101.475474ms","start":"2026-05-29T22:02:51.675655Z","end":"2026-05-29T22:02:51.77713Z","steps":["trace[1928620071] 'process raft request' (duration: 83.391136ms)","trace[1928620071] 'compare' (duration: 17.405846ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:51.802353Z","caller":"traceutil/trace.go:171","msg":"trace[694751475] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"125.73154ms","start":"2026-05-29T22:02:51.676607Z","end":"2026-05-29T22:02:51.802338Z","steps":["trace[694751475] 'process raft request' (duration: 125.632348ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:03.738409Z","caller":"traceutil/trace.go:171","msg":"trace[1885397744] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"118.188582ms","start":"2026-05-29T22:03:03.620189Z","end":"2026-05-29T22:03:03.738378Z","steps":["trace[1885397744] 'process raft request' (duration: 65.384859ms)","trace[1885397744] 'compare' (duration: 52.66415ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:13.267159Z","caller":"traceutil/trace.go:171","msg":"trace[2020649686] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"108.994157ms","start":"2026-05-29T22:03:13.158137Z","end":"2026-05-29T22:03:13.267131Z","steps":["trace[2020649686] 'process raft request' (duration: 108.588751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.483212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.369091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:03:13.483787Z","caller":"traceutil/trace.go:171","msg":"trace[432277345] linearizableReadLoop","detail":"{readStateIndex:5464; appliedIndex:5462; }","duration":"176.665979ms","start":"2026-05-29T22:03:13.307094Z","end":"2026-05-29T22:03:13.48376Z","steps":["trace[432277345] 'read index received' (duration: 62.672167ms)","trace[432277345] 'applied index is now lower than readState.Index' (duration: 113.992612ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:13.483912Z","caller":"traceutil/trace.go:171","msg":"trace[1033630690] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"211.684216ms","start":"2026-05-29T22:03:13.272188Z","end":"2026-05-29T22:03:13.483872Z","steps":["trace[1033630690] 'process raft request' (duration: 211.490123ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:13.483962Z","caller":"traceutil/trace.go:171","msg":"trace[3120117] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"212.224606ms","start":"2026-05-29T22:03:13.271728Z","end":"2026-05-29T22:03:13.483952Z","steps":["trace[3120117] 'process raft request' (duration: 98.02359ms)","trace[3120117] 'compare' (duration: 113.212259ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:13.484095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.001636ms","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-05-29T22:03:13.484535Z","caller":"traceutil/trace.go:171","msg":"trace[2011752319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5275; }","duration":"177.479973ms","start":"2026-05-29T22:03:13.307041Z","end":"2026-05-29T22:03:13.484521Z","steps":["trace[2011752319] 'agreement among raft nodes before linearized reading' (duration: 176.953944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.882892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.360548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:03:13.88319Z","caller":"traceutil/trace.go:171","msg":"trace[649451597] linearizableReadLoop","detail":"{readStateIndex:5465; appliedIndex:5464; }","duration":"256.428519ms","start":"2026-05-29T22:03:13.626745Z","end":"2026-05-29T22:03:13.883174Z","steps":["trace[649451597] 'read index received' (duration: 29.46µs)","trace[649451597] 'applied index is now lower than readState.Index' (duration: 256.397719ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:13.883216Z","caller":"traceutil/trace.go:171","msg":"trace[1624803788] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"395.275205ms","start":"2026-05-29T22:03:13.487911Z","end":"2026-05-29T22:03:13.883187Z","steps":["trace[1624803788] 'process raft request' (duration: 111.538431ms)","trace[1624803788] 'compare' (duration: 282.958991ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:13.883519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:13.487893Z","time spent":"395.559199ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:03:13.883836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.037839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-db-init-v6fsz\" ","response":"range_response_count:1 size:7349"} {"level":"info","ts":"2026-05-29T22:03:13.883914Z","caller":"traceutil/trace.go:171","msg":"trace[767193641] range","detail":"{range_begin:/registry/pods/openstack/keystone-db-init-v6fsz; range_end:; response_count:1; response_revision:5276; }","duration":"257.154601ms","start":"2026-05-29T22:03:13.626739Z","end":"2026-05-29T22:03:13.883894Z","steps":["trace[767193641] 'agreement among raft nodes before linearized reading' (duration: 256.683753ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:14.008172Z","caller":"traceutil/trace.go:171","msg":"trace[1570294160] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"112.234502ms","start":"2026-05-29T22:03:13.895914Z","end":"2026-05-29T22:03:14.008149Z","steps":["trace[1570294160] 'process raft request' (duration: 110.500543ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:14.036286Z","caller":"traceutil/trace.go:171","msg":"trace[1091231030] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"107.661473ms","start":"2026-05-29T22:03:13.928598Z","end":"2026-05-29T22:03:14.036259Z","steps":["trace[1091231030] 'process raft request' (duration: 107.315098ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:48.284079Z","caller":"traceutil/trace.go:171","msg":"trace[530152166] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"125.949909ms","start":"2026-05-29T22:03:48.158103Z","end":"2026-05-29T22:03:48.284053Z","steps":["trace[530152166] 'process raft request' (duration: 125.874997ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:48.284129Z","caller":"traceutil/trace.go:171","msg":"trace[1413341644] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"128.437359ms","start":"2026-05-29T22:03:48.155656Z","end":"2026-05-29T22:03:48.284094Z","steps":["trace[1413341644] 'process raft request' (duration: 34.987922ms)","trace[1413341644] 'compare' (duration: 93.147902ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:51.606491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-05-29T22:03:51.683007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"74.622106ms","hash":633257970,"current-db-size-bytes":53723136,"current-db-size":"54 MB","current-db-size-in-use-bytes":29569024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-29T22:03:51.683067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":633257970,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-05-29T22:05:51.360949Z","caller":"traceutil/trace.go:171","msg":"trace[50166630] linearizableReadLoop","detail":"{readStateIndex:6611; appliedIndex:6610; }","duration":"123.463492ms","start":"2026-05-29T22:05:51.237453Z","end":"2026-05-29T22:05:51.360917Z","steps":["trace[50166630] 'read index received' (duration: 99.34483ms)","trace[50166630] 'applied index is now lower than readState.Index' (duration: 24.117282ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:05:51.36113Z","caller":"traceutil/trace.go:171","msg":"trace[1578008097] transaction","detail":"{read_only:false; response_revision:6384; number_of_response:1; }","duration":"145.333521ms","start":"2026-05-29T22:05:51.215776Z","end":"2026-05-29T22:05:51.36111Z","steps":["trace[1578008097] 'process raft request' (duration: 121.100418ms)","trace[1578008097] 'compare' (duration: 23.870708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:05:51.361305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.839038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-05-29T22:05:51.361341Z","caller":"traceutil/trace.go:171","msg":"trace[792800822] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6384; }","duration":"123.916069ms","start":"2026-05-29T22:05:51.237416Z","end":"2026-05-29T22:05:51.361332Z","steps":["trace[792800822] 'agreement among raft nodes before linearized reading' (duration: 123.775047ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:06:17.015556Z","caller":"traceutil/trace.go:171","msg":"trace[491782987] linearizableReadLoop","detail":"{readStateIndex:6808; appliedIndex:6807; }","duration":"162.518074ms","start":"2026-05-29T22:06:16.853008Z","end":"2026-05-29T22:06:17.015526Z","steps":["trace[491782987] 'read index received' (duration: 112.584902ms)","trace[491782987] 'applied index is now lower than readState.Index' (duration: 49.931762ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:06:17.016063Z","caller":"traceutil/trace.go:171","msg":"trace[543185575] transaction","detail":"{read_only:false; response_revision:6573; number_of_response:1; }","duration":"165.971755ms","start":"2026-05-29T22:06:16.850074Z","end":"2026-05-29T22:06:17.016045Z","steps":["trace[543185575] 'process raft request' (duration: 115.574246ms)","trace[543185575] 'compare' (duration: 49.520366ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:06:17.016339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.021929ms","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-05-29T22:06:17.016428Z","caller":"traceutil/trace.go:171","msg":"trace[466331155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6573; }","duration":"146.165822ms","start":"2026-05-29T22:06:16.870243Z","end":"2026-05-29T22:06:17.016408Z","steps":["trace[466331155] 'agreement among raft nodes before linearized reading' (duration: 145.956039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:06:17.0166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.55874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107251"} {"level":"info","ts":"2026-05-29T22:06:17.016693Z","caller":"traceutil/trace.go:171","msg":"trace[2118412756] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6573; }","duration":"163.672782ms","start":"2026-05-29T22:06:16.852998Z","end":"2026-05-29T22:06:17.016671Z","steps":["trace[2118412756] 'agreement among raft nodes before linearized reading' (duration: 163.315766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:06:17.017654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.348317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T22:06:17.017716Z","caller":"traceutil/trace.go:171","msg":"trace[1786692084] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:6572; }","duration":"194.444008ms","start":"2026-05-29T22:06:16.823257Z","end":"2026-05-29T22:06:17.017701Z","steps":["trace[1786692084] 'count revisions from in-memory index tree' (duration: 194.283656ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:07:12.942708Z","caller":"traceutil/trace.go:171","msg":"trace[1083728227] linearizableReadLoop","detail":"{readStateIndex:7126; appliedIndex:7125; }","duration":"216.970619ms","start":"2026-05-29T22:07:12.725714Z","end":"2026-05-29T22:07:12.942685Z","steps":["trace[1083728227] 'read index received' (duration: 216.742679ms)","trace[1083728227] 'applied index is now lower than readState.Index' (duration: 227.27µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:07:12.94276Z","caller":"traceutil/trace.go:171","msg":"trace[1709053086] transaction","detail":"{read_only:false; response_revision:6879; number_of_response:1; }","duration":"362.970954ms","start":"2026-05-29T22:07:12.579757Z","end":"2026-05-29T22:07:12.942728Z","steps":["trace[1709053086] 'process raft request' (duration: 362.727904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:07:12.942905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:07:12.579748Z","time spent":"363.085449ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:07:12.942914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.185207ms","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-05-29T22:07:12.942941Z","caller":"traceutil/trace.go:171","msg":"trace[603312879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6879; }","duration":"217.260451ms","start":"2026-05-29T22:07:12.725672Z","end":"2026-05-29T22:07:12.942933Z","steps":["trace[603312879] 'agreement among raft nodes before linearized reading' (duration: 217.123855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:07:12.97672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.24139ms","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-05-29T22:07:12.976868Z","caller":"traceutil/trace.go:171","msg":"trace[838497570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6880; }","duration":"244.452499ms","start":"2026-05-29T22:07:12.732399Z","end":"2026-05-29T22:07:12.976851Z","steps":["trace[838497570] 'agreement among raft nodes before linearized reading' (duration: 244.156697ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:07:17.404246Z","caller":"traceutil/trace.go:171","msg":"trace[38561070] transaction","detail":"{read_only:false; response_revision:6903; number_of_response:1; }","duration":"179.891577ms","start":"2026-05-29T22:07:17.224334Z","end":"2026-05-29T22:07:17.404225Z","steps":["trace[38561070] 'process raft request' (duration: 179.394557ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:08:27.439763Z","caller":"traceutil/trace.go:171","msg":"trace[70892189] linearizableReadLoop","detail":"{readStateIndex:7516; appliedIndex:7515; }","duration":"138.120953ms","start":"2026-05-29T22:08:27.301619Z","end":"2026-05-29T22:08:27.43974Z","steps":["trace[70892189] 'read index received' (duration: 138.007029ms)","trace[70892189] 'applied index is now lower than readState.Index' (duration: 113.124µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:08:27.439824Z","caller":"traceutil/trace.go:171","msg":"trace[713847940] transaction","detail":"{read_only:false; response_revision:7254; number_of_response:1; }","duration":"151.414932ms","start":"2026-05-29T22:08:27.288374Z","end":"2026-05-29T22:08:27.439789Z","steps":["trace[713847940] 'process raft request' (duration: 151.255587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:08:27.439915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.269588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:08:27.439946Z","caller":"traceutil/trace.go:171","msg":"trace[570259463] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:7254; }","duration":"138.341221ms","start":"2026-05-29T22:08:27.301594Z","end":"2026-05-29T22:08:27.439936Z","steps":["trace[570259463] 'agreement among raft nodes before linearized reading' (duration: 138.271688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:08:27.440114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.174075ms","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-05-29T22:08:27.440182Z","caller":"traceutil/trace.go:171","msg":"trace[2089416569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7254; }","duration":"127.269259ms","start":"2026-05-29T22:08:27.312896Z","end":"2026-05-29T22:08:27.440165Z","steps":["trace[2089416569] 'agreement among raft nodes before linearized reading' (duration: 127.107043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:08:27.440346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.732739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T22:08:27.440401Z","caller":"traceutil/trace.go:171","msg":"trace[771999971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7254; }","duration":"122.811302ms","start":"2026-05-29T22:08:27.317575Z","end":"2026-05-29T22:08:27.440386Z","steps":["trace[771999971] 'agreement among raft nodes before linearized reading' (duration: 122.659866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:08:27.440596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.998998ms","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-05-29T22:08:27.440746Z","caller":"traceutil/trace.go:171","msg":"trace[14532041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7254; }","duration":"123.183364ms","start":"2026-05-29T22:08:27.317553Z","end":"2026-05-29T22:08:27.440736Z","steps":["trace[14532041] 'agreement among raft nodes before linearized reading' (duration: 122.77866ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:08:51.612337Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5574} {"level":"info","ts":"2026-05-29T22:08:51.69826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5574,"took":"84.370087ms","hash":4283155799,"current-db-size-bytes":53723136,"current-db-size":"54 MB","current-db-size-in-use-bytes":18358272,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-29T22:08:51.69833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4283155799,"revision":5574,"compact-revision":2862}