{"level":"warn","ts":"2026-06-02T21:31:07.571361Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T21:31:07.571491Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-06-02T21:31:07.571585Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T21:31:07.5716Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-06-02T21:31:07.57163Z","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-06-02T21:31:07.572482Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-06-02T21:31:07.572581Z","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":16,"max-cpu-available":16,"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.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-06-02T21:31:07.575129Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.238334ms"} {"level":"info","ts":"2026-06-02T21:31:07.579342Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-06-02T21:31:07.579413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T21:31:07.579433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-06-02T21:31:07.57945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T21:31:07.579459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-06-02T21:31:07.579495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-06-02T21:31:07.58422Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T21:31:07.585344Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T21:31:07.586745Z","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-06-02T21:31:07.590085Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T21:31:07.590362Z","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-06-02T21:31:07.590303Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T21:31:07.590512Z","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-06-02T21:31:07.590573Z","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-06-02T21:31:07.591199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-06-02T21:31:07.594059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-06-02T21:31:07.597353Z","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-06-02T21:31:07.597519Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-06-02T21:31:07.597609Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-06-02T21:31:07.597706Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T21:31:07.597773Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T21:31:08.580035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T21:31:08.580151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T21:31:08.580185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-06-02T21:31:08.580209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-06-02T21:31:08.580222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-06-02T21:31:08.580245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-06-02T21:31:08.580265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-06-02T21:31:08.64394Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T21:31:08.64397Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T21:31:08.644046Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T21:31:08.644039Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:31:08.644465Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T21:31:08.644601Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T21:31:08.644978Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:31:08.645128Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:31:08.645173Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:31:08.647403Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-06-02T21:31:08.648238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-02T21:31:16.379087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.683978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:31:16.379209Z","caller":"traceutil/trace.go:171","msg":"trace[323122659] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"159.020945ms","start":"2026-06-02T21:31:16.22017Z","end":"2026-06-02T21:31:16.379191Z","steps":["trace[323122659] 'process raft request' (duration: 23.66143ms)","trace[323122659] 'compare' (duration: 134.567326ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:31:16.703203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.831273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:31:16.703397Z","caller":"traceutil/trace.go:171","msg":"trace[78389170] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"186.923669ms","start":"2026-06-02T21:31:16.516455Z","end":"2026-06-02T21:31:16.703378Z","steps":["trace[78389170] 'read index received' (duration: 4.783467ms)","trace[78389170] 'applied index is now lower than readState.Index' (duration: 182.138992ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:16.703455Z","caller":"traceutil/trace.go:171","msg":"trace[1195606078] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"314.465232ms","start":"2026-06-02T21:31:16.388934Z","end":"2026-06-02T21:31:16.703399Z","steps":["trace[1195606078] 'process raft request' (duration: 132.359912ms)","trace[1195606078] 'compare' (duration: 181.68568ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:31:16.703541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.085492ms","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-06-02T21:31:16.703572Z","caller":"traceutil/trace.go:171","msg":"trace[297955201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:329; }","duration":"187.153484ms","start":"2026-06-02T21:31:16.516407Z","end":"2026-06-02T21:31:16.703561Z","steps":["trace[297955201] 'agreement among raft nodes before linearized reading' (duration: 187.045251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:16.703634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:31:16.388918Z","time spent":"314.635536ms","remote":"127.0.0.1:40480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T21:31:45.494865Z","caller":"traceutil/trace.go:171","msg":"trace[1391840117] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"106.746314ms","start":"2026-06-02T21:31:45.388093Z","end":"2026-06-02T21:31:45.494839Z","steps":["trace[1391840117] 'process raft request' (duration: 105.697109ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:45.735298Z","caller":"traceutil/trace.go:171","msg":"trace[949358564] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"113.719444ms","start":"2026-06-02T21:31:45.621545Z","end":"2026-06-02T21:31:45.735265Z","steps":["trace[949358564] 'process raft request' (duration: 111.967942ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:45.735585Z","caller":"traceutil/trace.go:171","msg":"trace[211477948] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"108.154918ms","start":"2026-06-02T21:31:45.627402Z","end":"2026-06-02T21:31:45.735557Z","steps":["trace[211477948] 'process raft request' (duration: 107.679587ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:51.694324Z","caller":"traceutil/trace.go:171","msg":"trace[962274060] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"102.019189ms","start":"2026-06-02T21:31:51.59227Z","end":"2026-06-02T21:31:51.69429Z","steps":["trace[962274060] 'process raft request' (duration: 101.432295ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:52.015096Z","caller":"traceutil/trace.go:171","msg":"trace[876976064] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"236.491882ms","start":"2026-06-02T21:31:51.778566Z","end":"2026-06-02T21:31:52.015058Z","steps":["trace[876976064] 'read index received' (duration: 201.471556ms)","trace[876976064] 'applied index is now lower than readState.Index' (duration: 35.019176ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:52.015347Z","caller":"traceutil/trace.go:171","msg":"trace[1824712629] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"257.402052ms","start":"2026-06-02T21:31:51.757895Z","end":"2026-06-02T21:31:52.015297Z","steps":["trace[1824712629] 'process raft request' (duration: 257.109755ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:52.01541Z","caller":"traceutil/trace.go:171","msg":"trace[106257093] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"289.762662ms","start":"2026-06-02T21:31:51.725596Z","end":"2026-06-02T21:31:52.015359Z","steps":["trace[106257093] 'process raft request' (duration: 254.501851ms)","trace[106257093] 'compare' (duration: 34.746038ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:31:52.015529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.951514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-02T21:31:52.015569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.012374ms","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-06-02T21:31:52.015642Z","caller":"traceutil/trace.go:171","msg":"trace[383822292] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:629; }","duration":"237.110288ms","start":"2026-06-02T21:31:51.778504Z","end":"2026-06-02T21:31:52.015615Z","steps":["trace[383822292] 'agreement among raft nodes before linearized reading' (duration: 236.928614ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:52.015658Z","caller":"traceutil/trace.go:171","msg":"trace[50493139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"228.145258ms","start":"2026-06-02T21:31:51.787492Z","end":"2026-06-02T21:31:52.015638Z","steps":["trace[50493139] 'agreement among raft nodes before linearized reading' (duration: 227.862251ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:52.198243Z","caller":"traceutil/trace.go:171","msg":"trace[2093338413] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"172.631693ms","start":"2026-06-02T21:31:52.025574Z","end":"2026-06-02T21:31:52.198206Z","steps":["trace[2093338413] 'read index received' (duration: 124.895838ms)","trace[2093338413] 'applied index is now lower than readState.Index' (duration: 47.734325ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:52.198282Z","caller":"traceutil/trace.go:171","msg":"trace[1104713172] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"178.133307ms","start":"2026-06-02T21:31:52.020113Z","end":"2026-06-02T21:31:52.198247Z","steps":["trace[1104713172] 'process raft request' (duration: 177.83922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:52.198399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.800447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:31:52.198435Z","caller":"traceutil/trace.go:171","msg":"trace[1117380592] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:630; }","duration":"172.862198ms","start":"2026-06-02T21:31:52.02556Z","end":"2026-06-02T21:31:52.198422Z","steps":["trace[1117380592] 'agreement among raft nodes before linearized reading' (duration: 172.774186ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:32:07.659024Z","caller":"traceutil/trace.go:171","msg":"trace[832458308] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"173.519345ms","start":"2026-06-02T21:32:07.485473Z","end":"2026-06-02T21:32:07.658992Z","steps":["trace[832458308] 'process raft request' (duration: 133.285323ms)","trace[832458308] 'compare' (duration: 40.093318ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:33:22.591714Z","caller":"traceutil/trace.go:171","msg":"trace[783880906] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"368.280104ms","start":"2026-06-02T21:33:22.223408Z","end":"2026-06-02T21:33:22.591688Z","steps":["trace[783880906] 'process raft request' (duration: 368.08868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:22.59211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:33:22.223379Z","time spent":"368.433248ms","remote":"127.0.0.1:40476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:33:22.605969Z","caller":"traceutil/trace.go:171","msg":"trace[1140616632] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1927; }","duration":"295.084608ms","start":"2026-06-02T21:33:22.310861Z","end":"2026-06-02T21:33:22.605945Z","steps":["trace[1140616632] 'read index received' (duration: 280.74565ms)","trace[1140616632] 'applied index is now lower than readState.Index' (duration: 14.337608ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:33:22.606017Z","caller":"traceutil/trace.go:171","msg":"trace[2016660811] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"302.787595ms","start":"2026-06-02T21:33:22.303204Z","end":"2026-06-02T21:33:22.605992Z","steps":["trace[2016660811] 'process raft request' (duration: 302.542989ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:33:22.606111Z","caller":"traceutil/trace.go:171","msg":"trace[1793311985] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"174.696008ms","start":"2026-06-02T21:33:22.431368Z","end":"2026-06-02T21:33:22.606064Z","steps":["trace[1793311985] 'process raft request' (duration: 174.536444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:22.606298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.976667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T21:33:22.60635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.23453ms","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-06-02T21:33:22.606388Z","caller":"traceutil/trace.go:171","msg":"trace[1280443263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"159.08982ms","start":"2026-06-02T21:33:22.447268Z","end":"2026-06-02T21:33:22.606358Z","steps":["trace[1280443263] 'agreement among raft nodes before linearized reading' (duration: 158.901065ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:33:22.606444Z","caller":"traceutil/trace.go:171","msg":"trace[1242451483] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"236.400394ms","start":"2026-06-02T21:33:22.370021Z","end":"2026-06-02T21:33:22.606421Z","steps":["trace[1242451483] 'agreement among raft nodes before linearized reading' (duration: 236.109287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:22.606366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.459186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f882b4ab-bd5c-42a5-91c9-512fd3d1359d\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-06-02T21:33:22.60656Z","caller":"traceutil/trace.go:171","msg":"trace[1007047466] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f882b4ab-bd5c-42a5-91c9-512fd3d1359d; range_end:; response_count:1; response_revision:1859; }","duration":"295.693702ms","start":"2026-06-02T21:33:22.310803Z","end":"2026-06-02T21:33:22.606496Z","steps":["trace[1007047466] 'agreement among raft nodes before linearized reading' (duration: 295.298613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:22.606133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:33:22.303171Z","time spent":"302.901458ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:33:22.9935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.39592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:33:22.993805Z","caller":"traceutil/trace.go:171","msg":"trace[1847621905] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"322.360039ms","start":"2026-06-02T21:33:22.671408Z","end":"2026-06-02T21:33:22.993768Z","steps":["trace[1847621905] 'process raft request' (duration: 93.605351ms)","trace[1847621905] 'compare' (duration: 228.255806ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:33:22.993828Z","caller":"traceutil/trace.go:171","msg":"trace[848723826] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"322.465942ms","start":"2026-06-02T21:33:22.671334Z","end":"2026-06-02T21:33:22.9938Z","steps":["trace[848723826] 'process raft request' (duration: 322.302808ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:33:22.993829Z","caller":"traceutil/trace.go:171","msg":"trace[1746977551] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"322.075373ms","start":"2026-06-02T21:33:22.671734Z","end":"2026-06-02T21:33:22.99381Z","steps":["trace[1746977551] 'process raft request' (duration: 322.023612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:22.994104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:33:22.671713Z","time spent":"322.329969ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:33:22.994111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:33:22.671391Z","time spent":"322.582965ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:33:22.993884Z","caller":"traceutil/trace.go:171","msg":"trace[528739406] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"322.274428ms","start":"2026-06-02T21:33:22.6716Z","end":"2026-06-02T21:33:22.993875Z","steps":["trace[528739406] 'process raft request' (duration: 322.118484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:22.994355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:33:22.671314Z","time spent":"322.656527ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:33:22.994758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:33:22.671591Z","time spent":"322.874343ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:33:36.307725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.621467ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:33:36.307852Z","caller":"traceutil/trace.go:171","msg":"trace[1103374175] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1970; }","duration":"193.76034ms","start":"2026-06-02T21:33:36.114074Z","end":"2026-06-02T21:33:36.307834Z","steps":["trace[1103374175] 'range keys from in-memory index tree' (duration: 193.606257ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:33:36.307833Z","caller":"traceutil/trace.go:171","msg":"trace[1531433815] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"173.455268ms","start":"2026-06-02T21:33:36.134319Z","end":"2026-06-02T21:33:36.307775Z","steps":["trace[1531433815] 'read index received' (duration: 170.849705ms)","trace[1531433815] 'applied index is now lower than readState.Index' (duration: 2.604293ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:33:36.307902Z","caller":"traceutil/trace.go:171","msg":"trace[161421276] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"254.342231ms","start":"2026-06-02T21:33:36.053513Z","end":"2026-06-02T21:33:36.307855Z","steps":["trace[161421276] 'process raft request' (duration: 251.758158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:36.308101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.758935ms","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-06-02T21:33:36.308172Z","caller":"traceutil/trace.go:171","msg":"trace[1711936722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1971; }","duration":"173.879148ms","start":"2026-06-02T21:33:36.134279Z","end":"2026-06-02T21:33:36.308158Z","steps":["trace[1711936722] 'agreement among raft nodes before linearized reading' (duration: 173.617192ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:34:06.187657Z","caller":"traceutil/trace.go:171","msg":"trace[1970887857] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"109.039442ms","start":"2026-06-02T21:34:06.078588Z","end":"2026-06-02T21:34:06.187628Z","steps":["trace[1970887857] 'process raft request' (duration: 108.872238ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:34:11.898912Z","caller":"traceutil/trace.go:171","msg":"trace[1690435239] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"111.937852ms","start":"2026-06-02T21:34:11.786947Z","end":"2026-06-02T21:34:11.898884Z","steps":["trace[1690435239] 'process raft request' (duration: 111.83124ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:34:12.434784Z","caller":"traceutil/trace.go:171","msg":"trace[42562899] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"127.925013ms","start":"2026-06-02T21:34:12.306828Z","end":"2026-06-02T21:34:12.434753Z","steps":["trace[42562899] 'process raft request' (duration: 121.216809ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:34:13.00276Z","caller":"traceutil/trace.go:171","msg":"trace[2044617195] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2265; }","duration":"150.109455ms","start":"2026-06-02T21:34:12.852628Z","end":"2026-06-02T21:34:13.002737Z","steps":["trace[2044617195] 'read index received' (duration: 149.726236ms)","trace[2044617195] 'applied index is now lower than readState.Index' (duration: 382.179µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:34:13.002934Z","caller":"traceutil/trace.go:171","msg":"trace[190348559] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"220.170975ms","start":"2026-06-02T21:34:12.782719Z","end":"2026-06-02T21:34:13.00289Z","steps":["trace[190348559] 'process raft request' (duration: 219.775105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:34:13.003067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.375641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-02T21:34:13.003108Z","caller":"traceutil/trace.go:171","msg":"trace[684920795] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2185; }","duration":"150.538136ms","start":"2026-06-02T21:34:12.852559Z","end":"2026-06-02T21:34:13.003097Z","steps":["trace[684920795] 'agreement among raft nodes before linearized reading' (duration: 150.31657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:35:43.701727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.166072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:35:43.701874Z","caller":"traceutil/trace.go:171","msg":"trace[1582700098] linearizableReadLoop","detail":"{readStateIndex:2978; appliedIndex:2977; }","duration":"219.31561ms","start":"2026-06-02T21:35:43.482538Z","end":"2026-06-02T21:35:43.701853Z","steps":["trace[1582700098] 'read index received' (duration: 116.820659ms)","trace[1582700098] 'applied index is now lower than readState.Index' (duration: 102.493281ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:35:43.701899Z","caller":"traceutil/trace.go:171","msg":"trace[1752383422] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"234.545649ms","start":"2026-06-02T21:35:43.467314Z","end":"2026-06-02T21:35:43.70186Z","steps":["trace[1752383422] 'process raft request' (duration: 132.12254ms)","trace[1752383422] 'compare' (duration: 101.876485ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:35:43.702056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.524634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-02T21:35:43.702462Z","caller":"traceutil/trace.go:171","msg":"trace[216901774] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2869; }","duration":"219.951615ms","start":"2026-06-02T21:35:43.4825Z","end":"2026-06-02T21:35:43.702451Z","steps":["trace[216901774] 'agreement among raft nodes before linearized reading' (duration: 219.396962ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:51.461155Z","caller":"traceutil/trace.go:171","msg":"trace[411102534] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"105.13526ms","start":"2026-06-02T21:39:51.355978Z","end":"2026-06-02T21:39:51.461113Z","steps":["trace[411102534] 'process raft request' (duration: 57.148046ms)","trace[411102534] 'compare' (duration: 47.106623ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:39:51.462057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.508961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110674"} {"level":"info","ts":"2026-06-02T21:39:51.462105Z","caller":"traceutil/trace.go:171","msg":"trace[2091898446] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5243; }","duration":"131.635934ms","start":"2026-06-02T21:39:51.330459Z","end":"2026-06-02T21:39:51.462095Z","steps":["trace[2091898446] 'agreement among raft nodes before linearized reading' (duration: 131.320936ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:56.993751Z","caller":"traceutil/trace.go:171","msg":"trace[1472427192] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"214.699348ms","start":"2026-06-02T21:39:56.779003Z","end":"2026-06-02T21:39:56.993702Z","steps":["trace[1472427192] 'process raft request' (duration: 154.096079ms)","trace[1472427192] 'compare' (duration: 60.372314ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:40:05.648403Z","caller":"traceutil/trace.go:171","msg":"trace[399937302] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"168.443826ms","start":"2026-06-02T21:40:05.479922Z","end":"2026-06-02T21:40:05.648366Z","steps":["trace[399937302] 'process raft request' (duration: 168.16017ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:41:08.688044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3006} {"level":"info","ts":"2026-06-02T21:41:08.778295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3006,"took":"88.460038ms","hash":1542312348,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":31739904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T21:41:08.778363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1542312348,"revision":3006,"compact-revision":-1} {"level":"info","ts":"2026-06-02T21:46:08.828909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6079} {"level":"info","ts":"2026-06-02T21:46:08.828936Z","caller":"traceutil/trace.go:171","msg":"trace[1958725817] transaction","detail":"{read_only:false; response_revision:8172; number_of_response:1; }","duration":"104.270992ms","start":"2026-06-02T21:46:08.72464Z","end":"2026-06-02T21:46:08.82891Z","steps":["trace[1958725817] 'process raft request' (duration: 104.146219ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:08.829118Z","caller":"traceutil/trace.go:171","msg":"trace[115030560] compact","detail":"{revision:6079; response_revision:8172; }","duration":"124.600462ms","start":"2026-06-02T21:46:08.70449Z","end":"2026-06-02T21:46:08.82909Z","steps":["trace[115030560] 'process raft request' (duration: 35.198018ms)","trace[115030560] 'check and update compact revision' (duration: 88.987474ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:46:09.118002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6079,"took":"287.077737ms","hash":3506039298,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":21999616,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-02T21:46:09.11813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3506039298,"revision":6079,"compact-revision":3006} {"level":"warn","ts":"2026-06-02T21:46:16.546815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.491333ms","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-06-02T21:46:16.546876Z","caller":"traceutil/trace.go:171","msg":"trace[2010254097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8347; }","duration":"369.618365ms","start":"2026-06-02T21:46:16.177245Z","end":"2026-06-02T21:46:16.546864Z","steps":["trace[2010254097] 'range keys from in-memory index tree' (duration: 369.302888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:46:16.546905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:46:16.177216Z","time spent":"369.680128ms","remote":"127.0.0.1:40540","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-06-02T21:46:16.547492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.166617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:46:16.547537Z","caller":"traceutil/trace.go:171","msg":"trace[2086430788] linearizableReadLoop","detail":"{readStateIndex:8642; appliedIndex:8641; }","duration":"191.149096ms","start":"2026-06-02T21:46:16.356381Z","end":"2026-06-02T21:46:16.54753Z","steps":["trace[2086430788] 'read index received' (duration: 33.796695ms)","trace[2086430788] 'applied index is now lower than readState.Index' (duration: 157.351081ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:46:16.547646Z","caller":"traceutil/trace.go:171","msg":"trace[1035993287] transaction","detail":"{read_only:false; response_revision:8348; number_of_response:1; }","duration":"370.236761ms","start":"2026-06-02T21:46:16.177402Z","end":"2026-06-02T21:46:16.547639Z","steps":["trace[1035993287] 'process raft request' (duration: 212.87984ms)","trace[1035993287] 'compare' (duration: 157.096695ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:46:16.547685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:46:16.177367Z","time spent":"370.301743ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:46:16.547743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.370561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:46:16.547761Z","caller":"traceutil/trace.go:171","msg":"trace[457957211] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8348; }","duration":"191.429893ms","start":"2026-06-02T21:46:16.356327Z","end":"2026-06-02T21:46:16.547757Z","steps":["trace[457957211] 'agreement among raft nodes before linearized reading' (duration: 191.393492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:46:16.568458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.672903ms","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-06-02T21:46:16.568543Z","caller":"traceutil/trace.go:171","msg":"trace[582008533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8349; }","duration":"185.796017ms","start":"2026-06-02T21:46:16.382728Z","end":"2026-06-02T21:46:16.568524Z","steps":["trace[582008533] 'agreement among raft nodes before linearized reading' (duration: 185.587862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:46:16.568464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.974144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:46:16.568703Z","caller":"traceutil/trace.go:171","msg":"trace[302193958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8349; }","duration":"121.245741ms","start":"2026-06-02T21:46:16.447406Z","end":"2026-06-02T21:46:16.568652Z","steps":["trace[302193958] 'agreement among raft nodes before linearized reading' (duration: 120.982634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:46:28.777413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.488066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:46:28.777528Z","caller":"traceutil/trace.go:171","msg":"trace[1511408617] linearizableReadLoop","detail":"{readStateIndex:8709; appliedIndex:8708; }","duration":"149.461011ms","start":"2026-06-02T21:46:28.628048Z","end":"2026-06-02T21:46:28.777509Z","steps":["trace[1511408617] 'read index received' (duration: 37.726979ms)","trace[1511408617] 'applied index is now lower than readState.Index' (duration: 111.732612ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:46:28.777548Z","caller":"traceutil/trace.go:171","msg":"trace[708298131] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"165.074627ms","start":"2026-06-02T21:46:28.612443Z","end":"2026-06-02T21:46:28.777518Z","steps":["trace[708298131] 'process raft request' (duration: 53.380076ms)","trace[708298131] 'compare' (duration: 111.24816ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:46:28.777805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.721009ms","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-06-02T21:46:28.777912Z","caller":"traceutil/trace.go:171","msg":"trace[1663431971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8412; }","duration":"149.888002ms","start":"2026-06-02T21:46:28.628Z","end":"2026-06-02T21:46:28.777888Z","steps":["trace[1663431971] 'agreement among raft nodes before linearized reading' (duration: 149.581843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:46:28.777907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.576538ms","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-06-02T21:46:28.778011Z","caller":"traceutil/trace.go:171","msg":"trace[336963643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8412; }","duration":"140.702251ms","start":"2026-06-02T21:46:28.63728Z","end":"2026-06-02T21:46:28.777982Z","steps":["trace[336963643] 'agreement among raft nodes before linearized reading' (duration: 140.387192ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:28.92174Z","caller":"traceutil/trace.go:171","msg":"trace[138244145] linearizableReadLoop","detail":"{readStateIndex:8710; appliedIndex:8709; }","duration":"139.072752ms","start":"2026-06-02T21:46:28.78264Z","end":"2026-06-02T21:46:28.921713Z","steps":["trace[138244145] 'read index received' (duration: 103.895664ms)","trace[138244145] 'applied index is now lower than readState.Index' (duration: 35.176038ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:46:28.921835Z","caller":"traceutil/trace.go:171","msg":"trace[1153534012] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"139.937852ms","start":"2026-06-02T21:46:28.781848Z","end":"2026-06-02T21:46:28.921786Z","steps":["trace[1153534012] 'process raft request' (duration: 104.662121ms)","trace[1153534012] 'compare' (duration: 35.055455ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:46:28.921974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.292357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19408"} {"level":"info","ts":"2026-06-02T21:46:28.922073Z","caller":"traceutil/trace.go:171","msg":"trace[236556502] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8413; }","duration":"139.42732ms","start":"2026-06-02T21:46:28.782622Z","end":"2026-06-02T21:46:28.922049Z","steps":["trace[236556502] 'agreement among raft nodes before linearized reading' (duration: 139.196974ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:28.936645Z","caller":"traceutil/trace.go:171","msg":"trace[1471452665] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"154.020091ms","start":"2026-06-02T21:46:28.782604Z","end":"2026-06-02T21:46:28.936624Z","steps":["trace[1471452665] 'process raft request' (duration: 153.820256ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:30.169986Z","caller":"traceutil/trace.go:171","msg":"trace[2099540368] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"113.886403ms","start":"2026-06-02T21:46:30.056063Z","end":"2026-06-02T21:46:30.16995Z","steps":["trace[2099540368] 'process raft request' (duration: 113.467083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:46:56.678808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.99702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T21:46:56.67888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.893723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:46:56.678935Z","caller":"traceutil/trace.go:171","msg":"trace[2110368154] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8615; }","duration":"154.219846ms","start":"2026-06-02T21:46:56.52469Z","end":"2026-06-02T21:46:56.678909Z","steps":["trace[2110368154] 'count revisions from in-memory index tree' (duration: 153.858488ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:56.678972Z","caller":"traceutil/trace.go:171","msg":"trace[211553699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8615; }","duration":"231.035026ms","start":"2026-06-02T21:46:56.447913Z","end":"2026-06-02T21:46:56.678948Z","steps":["trace[211553699] 'range keys from in-memory index tree' (duration: 230.78128ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:56.869777Z","caller":"traceutil/trace.go:171","msg":"trace[1315188949] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"138.666461ms","start":"2026-06-02T21:46:56.731096Z","end":"2026-06-02T21:46:56.869762Z","steps":["trace[1315188949] 'process raft request' (duration: 138.603999ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:56.869626Z","caller":"traceutil/trace.go:171","msg":"trace[1925449359] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"151.821968ms","start":"2026-06-02T21:46:56.717767Z","end":"2026-06-02T21:46:56.869589Z","steps":["trace[1925449359] 'process raft request' (duration: 151.436189ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:47:01.02541Z","caller":"traceutil/trace.go:171","msg":"trace[1913233921] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"142.147075ms","start":"2026-06-02T21:47:00.883233Z","end":"2026-06-02T21:47:01.02538Z","steps":["trace[1913233921] 'process raft request' (duration: 141.988201ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:47:10.277298Z","caller":"traceutil/trace.go:171","msg":"trace[41732054] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"141.93198ms","start":"2026-06-02T21:47:10.135337Z","end":"2026-06-02T21:47:10.277269Z","steps":["trace[41732054] 'process raft request' (duration: 141.753166ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:47:10.410689Z","caller":"traceutil/trace.go:171","msg":"trace[205043518] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"269.247138ms","start":"2026-06-02T21:47:10.141403Z","end":"2026-06-02T21:47:10.41065Z","steps":["trace[205043518] 'process raft request' (duration: 269.045963ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:47:10.410808Z","caller":"traceutil/trace.go:171","msg":"trace[71510203] linearizableReadLoop","detail":"{readStateIndex:9041; appliedIndex:9039; }","duration":"130.664368ms","start":"2026-06-02T21:47:10.280105Z","end":"2026-06-02T21:47:10.410769Z","steps":["trace[71510203] 'read index received' (duration: 130.32682ms)","trace[71510203] 'applied index is now lower than readState.Index' (duration: 335.958µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:47:10.410949Z","caller":"traceutil/trace.go:171","msg":"trace[613665328] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"254.075612ms","start":"2026-06-02T21:47:10.15686Z","end":"2026-06-02T21:47:10.410935Z","steps":["trace[613665328] 'process raft request' (duration: 253.723084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:47:10.411026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.924035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:47:10.411149Z","caller":"traceutil/trace.go:171","msg":"trace[562309788] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8731; }","duration":"131.12534ms","start":"2026-06-02T21:47:10.279997Z","end":"2026-06-02T21:47:10.411122Z","steps":["trace[562309788] 'agreement among raft nodes before linearized reading' (duration: 130.913595ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:47:11.302622Z","caller":"traceutil/trace.go:171","msg":"trace[660644] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"116.340733ms","start":"2026-06-02T21:47:11.186246Z","end":"2026-06-02T21:47:11.302587Z","steps":["trace[660644] 'process raft request' (duration: 116.031626ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:47:11.42823Z","caller":"traceutil/trace.go:171","msg":"trace[698129088] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"120.418692ms","start":"2026-06-02T21:47:11.307776Z","end":"2026-06-02T21:47:11.428195Z","steps":["trace[698129088] 'process raft request' (duration: 86.466314ms)","trace[698129088] 'compare' (duration: 33.814444ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:47:11.481078Z","caller":"traceutil/trace.go:171","msg":"trace[1842925523] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"172.918557ms","start":"2026-06-02T21:47:11.308122Z","end":"2026-06-02T21:47:11.481041Z","steps":["trace[1842925523] 'process raft request' (duration: 172.731083ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:47:25.97391Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e0.wal"} {"level":"info","ts":"2026-06-02T21:48:28.199419Z","caller":"traceutil/trace.go:171","msg":"trace[1411698251] linearizableReadLoop","detail":"{readStateIndex:9820; appliedIndex:9819; }","duration":"175.46154ms","start":"2026-06-02T21:48:28.023881Z","end":"2026-06-02T21:48:28.199342Z","steps":["trace[1411698251] 'read index received' (duration: 175.307917ms)","trace[1411698251] 'applied index is now lower than readState.Index' (duration: 152.053µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:48:28.199566Z","caller":"traceutil/trace.go:171","msg":"trace[2070697806] transaction","detail":"{read_only:false; response_revision:9489; number_of_response:1; }","duration":"189.849516ms","start":"2026-06-02T21:48:28.009673Z","end":"2026-06-02T21:48:28.199522Z","steps":["trace[2070697806] 'process raft request' (duration: 189.422626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:48:28.199793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.89111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vbszl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-02T21:48:28.199921Z","caller":"traceutil/trace.go:171","msg":"trace[1557688008] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vbszl; range_end:; response_count:1; response_revision:9489; }","duration":"176.075694ms","start":"2026-06-02T21:48:28.023808Z","end":"2026-06-02T21:48:28.199884Z","steps":["trace[1557688008] 'agreement among raft nodes before linearized reading' (duration: 175.756746ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:48:50.791478Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T21:48:50.805283Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-02T21:48:50.805477Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-02T21:51:08.838018Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8171} {"level":"info","ts":"2026-06-02T21:51:08.904783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8171,"took":"64.690801ms","hash":1718224754,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":29810688,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T21:51:08.9052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1718224754,"revision":8171,"compact-revision":6079} {"level":"info","ts":"2026-06-02T21:52:15.179164Z","caller":"traceutil/trace.go:171","msg":"trace[788752232] linearizableReadLoop","detail":"{readStateIndex:11739; appliedIndex:11737; }","duration":"110.782192ms","start":"2026-06-02T21:52:15.068348Z","end":"2026-06-02T21:52:15.17913Z","steps":["trace[788752232] 'read index received' (duration: 52.417325ms)","trace[788752232] 'applied index is now lower than readState.Index' (duration: 58.362777ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:52:15.179339Z","caller":"traceutil/trace.go:171","msg":"trace[1103629642] transaction","detail":"{read_only:false; response_revision:11354; number_of_response:1; }","duration":"126.055111ms","start":"2026-06-02T21:52:15.053243Z","end":"2026-06-02T21:52:15.179298Z","steps":["trace[1103629642] 'process raft request' (duration: 75.516772ms)","trace[1103629642] 'compare' (duration: 50.22195ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:52:15.179476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.23752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T21:52:15.179523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.489056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:52:15.179537Z","caller":"traceutil/trace.go:171","msg":"trace[97189750] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11354; }","duration":"110.331432ms","start":"2026-06-02T21:52:15.069193Z","end":"2026-06-02T21:52:15.179524Z","steps":["trace[97189750] 'agreement among raft nodes before linearized reading' (duration: 110.204269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:52:15.179521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.453015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T21:52:15.179569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.280571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:52:15.179584Z","caller":"traceutil/trace.go:171","msg":"trace[1858914210] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11354; }","duration":"110.571298ms","start":"2026-06-02T21:52:15.068998Z","end":"2026-06-02T21:52:15.179569Z","steps":["trace[1858914210] 'agreement among raft nodes before linearized reading' (duration: 110.470556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:52:15.179566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.10799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-06-02T21:52:15.179607Z","caller":"traceutil/trace.go:171","msg":"trace[1034356424] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11354; }","duration":"110.343123ms","start":"2026-06-02T21:52:15.069254Z","end":"2026-06-02T21:52:15.179597Z","steps":["trace[1034356424] 'agreement among raft nodes before linearized reading' (duration: 110.273961ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:52:15.179649Z","caller":"traceutil/trace.go:171","msg":"trace[1463039903] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11354; }","duration":"111.218514ms","start":"2026-06-02T21:52:15.068411Z","end":"2026-06-02T21:52:15.179629Z","steps":["trace[1463039903] 'agreement among raft nodes before linearized reading' (duration: 110.972678ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:52:15.179614Z","caller":"traceutil/trace.go:171","msg":"trace[694495014] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11354; }","duration":"110.564348ms","start":"2026-06-02T21:52:15.069031Z","end":"2026-06-02T21:52:15.179596Z","steps":["trace[694495014] 'agreement among raft nodes before linearized reading' (duration: 110.413654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:52:15.179788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.370027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-06-02T21:52:15.179898Z","caller":"traceutil/trace.go:171","msg":"trace[1147443043] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11354; }","duration":"111.567432ms","start":"2026-06-02T21:52:15.068312Z","end":"2026-06-02T21:52:15.17988Z","steps":["trace[1147443043] 'agreement among raft nodes before linearized reading' (duration: 111.049669ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:56:08.844653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10762} {"level":"info","ts":"2026-06-02T21:56:08.928202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10762,"took":"81.519508ms","hash":4028110333,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":27222016,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-02T21:56:08.928265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4028110333,"revision":10762,"compact-revision":8171} {"level":"info","ts":"2026-06-02T21:58:50.593885Z","caller":"traceutil/trace.go:171","msg":"trace[465128868] transaction","detail":"{read_only:false; response_revision:13996; number_of_response:1; }","duration":"186.011861ms","start":"2026-06-02T21:58:50.40784Z","end":"2026-06-02T21:58:50.593852Z","steps":["trace[465128868] 'process raft request' (duration: 185.807976ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:59:00.328036Z","caller":"traceutil/trace.go:171","msg":"trace[66908400] linearizableReadLoop","detail":"{readStateIndex:14645; appliedIndex:14644; }","duration":"113.399247ms","start":"2026-06-02T21:59:00.214605Z","end":"2026-06-02T21:59:00.328004Z","steps":["trace[66908400] 'read index received' (duration: 52.884397ms)","trace[66908400] 'applied index is now lower than readState.Index' (duration: 60.51373ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:59:00.328119Z","caller":"traceutil/trace.go:171","msg":"trace[680386617] transaction","detail":"{read_only:false; response_revision:14165; number_of_response:1; }","duration":"135.859269ms","start":"2026-06-02T21:59:00.19222Z","end":"2026-06-02T21:59:00.328079Z","steps":["trace[680386617] 'process raft request' (duration: 75.40397ms)","trace[680386617] 'compare' (duration: 60.211483ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:59:00.328261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.647162ms","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-06-02T21:59:00.328298Z","caller":"traceutil/trace.go:171","msg":"trace[1646257388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14165; }","duration":"113.765587ms","start":"2026-06-02T21:59:00.214522Z","end":"2026-06-02T21:59:00.328288Z","steps":["trace[1646257388] 'agreement among raft nodes before linearized reading' (duration: 113.596732ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:00:39.985036Z","caller":"traceutil/trace.go:171","msg":"trace[712679275] transaction","detail":"{read_only:false; response_revision:15282; number_of_response:1; }","duration":"105.171059ms","start":"2026-06-02T22:00:39.879837Z","end":"2026-06-02T22:00:39.985008Z","steps":["trace[712679275] 'process raft request' (duration: 104.624296ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:00:40.525866Z","caller":"traceutil/trace.go:171","msg":"trace[1259721089] transaction","detail":"{read_only:false; response_revision:15285; number_of_response:1; }","duration":"243.864386ms","start":"2026-06-02T22:00:40.281963Z","end":"2026-06-02T22:00:40.525828Z","steps":["trace[1259721089] 'process raft request' (duration: 243.63259ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:00:40.871137Z","caller":"traceutil/trace.go:171","msg":"trace[1403322563] transaction","detail":"{read_only:false; response_revision:15287; number_of_response:1; }","duration":"414.34514ms","start":"2026-06-02T22:00:40.456762Z","end":"2026-06-02T22:00:40.871107Z","steps":["trace[1403322563] 'process raft request' (duration: 414.261148ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:00:40.871341Z","caller":"traceutil/trace.go:171","msg":"trace[1244438731] linearizableReadLoop","detail":"{readStateIndex:15802; appliedIndex:15799; }","duration":"311.204491ms","start":"2026-06-02T22:00:40.560125Z","end":"2026-06-02T22:00:40.87133Z","steps":["trace[1244438731] 'read index received' (duration: 310.771701ms)","trace[1244438731] 'applied index is now lower than readState.Index' (duration: 430.92µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:00:40.871333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:00:40.456734Z","time spent":"414.488624ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:00:40.87144Z","caller":"traceutil/trace.go:171","msg":"trace[1471713691] transaction","detail":"{read_only:false; response_revision:15288; number_of_response:1; }","duration":"414.493714ms","start":"2026-06-02T22:00:40.456939Z","end":"2026-06-02T22:00:40.871433Z","steps":["trace[1471713691] 'process raft request' (duration: 414.129595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:00:40.871555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:00:40.456927Z","time spent":"414.540725ms","remote":"127.0.0.1:40540","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-06-02T22:00:40.871604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.486238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vbszl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-02T22:00:40.871646Z","caller":"traceutil/trace.go:171","msg":"trace[2142565835] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vbszl; range_end:; response_count:1; response_revision:15288; }","duration":"311.57087ms","start":"2026-06-02T22:00:40.560064Z","end":"2026-06-02T22:00:40.871635Z","steps":["trace[2142565835] 'agreement among raft nodes before linearized reading' (duration: 311.432346ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:00:40.871143Z","caller":"traceutil/trace.go:171","msg":"trace[1745693464] transaction","detail":"{read_only:false; response_revision:15286; number_of_response:1; }","duration":"589.025826ms","start":"2026-06-02T22:00:40.282085Z","end":"2026-06-02T22:00:40.871111Z","steps":["trace[1745693464] 'process raft request' (duration: 588.76286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:00:40.871681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:00:40.560042Z","time spent":"311.633042ms","remote":"127.0.0.1:40476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-vbszl\" "} {"level":"warn","ts":"2026-06-02T22:00:40.871817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:00:40.282049Z","time spent":"589.657861ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:00:40.884185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.771031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T22:00:40.884252Z","caller":"traceutil/trace.go:171","msg":"trace[1385527219] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15289; }","duration":"257.897954ms","start":"2026-06-02T22:00:40.626338Z","end":"2026-06-02T22:00:40.884236Z","steps":["trace[1385527219] 'agreement among raft nodes before linearized reading' (duration: 257.750191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:00:40.884254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.367593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-02T22:00:40.884315Z","caller":"traceutil/trace.go:171","msg":"trace[657915132] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15289; }","duration":"255.468985ms","start":"2026-06-02T22:00:40.628825Z","end":"2026-06-02T22:00:40.884294Z","steps":["trace[657915132] 'agreement among raft nodes before linearized reading' (duration: 255.25502ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:00:40.884311Z","caller":"traceutil/trace.go:171","msg":"trace[1949180722] transaction","detail":"{read_only:false; response_revision:15289; number_of_response:1; }","duration":"134.760242ms","start":"2026-06-02T22:00:40.749516Z","end":"2026-06-02T22:00:40.884276Z","steps":["trace[1949180722] 'process raft request' (duration: 134.317852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:00:40.884361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.498056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-06-02T22:00:40.884345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.362805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T22:00:40.884392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.199761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-02T22:00:40.884437Z","caller":"traceutil/trace.go:171","msg":"trace[1453637444] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15289; }","duration":"255.641999ms","start":"2026-06-02T22:00:40.628773Z","end":"2026-06-02T22:00:40.884415Z","steps":["trace[1453637444] 'agreement among raft nodes before linearized reading' (duration: 255.338112ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:00:40.884463Z","caller":"traceutil/trace.go:171","msg":"trace[460022791] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15289; }","duration":"253.55857ms","start":"2026-06-02T22:00:40.63088Z","end":"2026-06-02T22:00:40.884439Z","steps":["trace[460022791] 'agreement among raft nodes before linearized reading' (duration: 253.316484ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:00:40.884467Z","caller":"traceutil/trace.go:171","msg":"trace[671593047] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15289; }","duration":"253.290344ms","start":"2026-06-02T22:00:40.631157Z","end":"2026-06-02T22:00:40.884448Z","steps":["trace[671593047] 'agreement among raft nodes before linearized reading' (duration: 253.001287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:00:40.884583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.628949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-06-02T22:00:40.884621Z","caller":"traceutil/trace.go:171","msg":"trace[1455798691] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15289; }","duration":"255.66505ms","start":"2026-06-02T22:00:40.628944Z","end":"2026-06-02T22:00:40.884609Z","steps":["trace[1455798691] 'agreement among raft nodes before linearized reading' (duration: 255.561378ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:01:08.858437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12866} {"level":"info","ts":"2026-06-02T22:01:08.938012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12866,"took":"76.630109ms","hash":1215931903,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":30425088,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T22:01:08.938113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1215931903,"revision":12866,"compact-revision":10762} {"level":"info","ts":"2026-06-02T22:06:08.864502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15431} {"level":"info","ts":"2026-06-02T22:06:08.963244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15431,"took":"95.089591ms","hash":259710765,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":32350208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T22:06:08.963312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":259710765,"revision":15431,"compact-revision":12866} {"level":"info","ts":"2026-06-02T22:06:21.109359Z","caller":"traceutil/trace.go:171","msg":"trace[201644576] transaction","detail":"{read_only:false; response_revision:17839; number_of_response:1; }","duration":"268.372847ms","start":"2026-06-02T22:06:20.840956Z","end":"2026-06-02T22:06:21.109329Z","steps":["trace[201644576] 'process raft request' (duration: 268.183823ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:06:21.142846Z","caller":"traceutil/trace.go:171","msg":"trace[90458569] transaction","detail":"{read_only:false; response_revision:17840; number_of_response:1; }","duration":"140.352882ms","start":"2026-06-02T22:06:21.002457Z","end":"2026-06-02T22:06:21.14281Z","steps":["trace[90458569] 'process raft request' (duration: 140.111347ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:09:14.072799Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T22:09:14.078885Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-02T22:09:14.079034Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-02T22:11:08.872225Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17757} {"level":"info","ts":"2026-06-02T22:11:08.969333Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17757,"took":"93.121988ms","hash":2644400480,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":34910208,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-02T22:11:08.969397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2644400480,"revision":17757,"compact-revision":15431} {"level":"warn","ts":"2026-06-02T22:12:10.762274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.068788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-06-02T22:12:10.762425Z","caller":"traceutil/trace.go:171","msg":"trace[2111572346] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:20573; }","duration":"146.327384ms","start":"2026-06-02T22:12:10.616063Z","end":"2026-06-02T22:12:10.76239Z","steps":["trace[2111572346] 'range keys from in-memory index tree' (duration: 145.810721ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:12:10.971982Z","caller":"traceutil/trace.go:171","msg":"trace[1887014636] transaction","detail":"{read_only:false; response_revision:20575; number_of_response:1; }","duration":"202.765518ms","start":"2026-06-02T22:12:10.769194Z","end":"2026-06-02T22:12:10.97196Z","steps":["trace[1887014636] 'process raft request' (duration: 202.612834ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:12:10.973421Z","caller":"traceutil/trace.go:171","msg":"trace[735665294] transaction","detail":"{read_only:false; response_revision:20576; number_of_response:1; }","duration":"196.19258ms","start":"2026-06-02T22:12:10.777182Z","end":"2026-06-02T22:12:10.973375Z","steps":["trace[735665294] 'process raft request' (duration: 195.951754ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:12:21.23127Z","caller":"traceutil/trace.go:171","msg":"trace[2125949912] transaction","detail":"{read_only:false; response_revision:20630; number_of_response:1; }","duration":"121.876527ms","start":"2026-06-02T22:12:21.109245Z","end":"2026-06-02T22:12:21.231122Z","steps":["trace[2125949912] 'process raft request' (duration: 121.649621ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:12:27.184499Z","caller":"traceutil/trace.go:171","msg":"trace[2047223654] transaction","detail":"{read_only:false; response_revision:20659; number_of_response:1; }","duration":"129.170191ms","start":"2026-06-02T22:12:27.055298Z","end":"2026-06-02T22:12:27.184469Z","steps":["trace[2047223654] 'process raft request' (duration: 128.937416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:27.598041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.993408ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-02T22:12:27.598276Z","caller":"traceutil/trace.go:171","msg":"trace[157640381] linearizableReadLoop","detail":"{readStateIndex:21354; appliedIndex:21353; }","duration":"339.857531ms","start":"2026-06-02T22:12:27.258384Z","end":"2026-06-02T22:12:27.598241Z","steps":["trace[157640381] 'read index received' (duration: 30.686937ms)","trace[157640381] 'applied index is now lower than readState.Index' (duration: 309.167904ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:12:27.598735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.336962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T22:12:27.598849Z","caller":"traceutil/trace.go:171","msg":"trace[425203191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20659; }","duration":"340.501595ms","start":"2026-06-02T22:12:27.258316Z","end":"2026-06-02T22:12:27.598818Z","steps":["trace[425203191] 'agreement among raft nodes before linearized reading' (duration: 340.160527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:27.598923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:12:27.258289Z","time spent":"340.611088ms","remote":"127.0.0.1:40540","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-06-02T22:12:27.599222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.743592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-02T22:12:27.599406Z","caller":"traceutil/trace.go:171","msg":"trace[1005140074] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20659; }","duration":"243.992638ms","start":"2026-06-02T22:12:27.355387Z","end":"2026-06-02T22:12:27.59938Z","steps":["trace[1005140074] 'agreement among raft nodes before linearized reading' (duration: 243.484186ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:12:27.896567Z","caller":"traceutil/trace.go:171","msg":"trace[280653692] transaction","detail":"{read_only:false; response_revision:20662; number_of_response:1; }","duration":"143.73856ms","start":"2026-06-02T22:12:27.752791Z","end":"2026-06-02T22:12:27.89653Z","steps":["trace[280653692] 'process raft request' (duration: 143.394532ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:12:27.896609Z","caller":"traceutil/trace.go:171","msg":"trace[636944814] transaction","detail":"{read_only:false; response_revision:20663; number_of_response:1; }","duration":"142.316767ms","start":"2026-06-02T22:12:27.754268Z","end":"2026-06-02T22:12:27.896584Z","steps":["trace[636944814] 'process raft request' (duration: 142.151523ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:12:27.897026Z","caller":"traceutil/trace.go:171","msg":"trace[1072731536] linearizableReadLoop","detail":"{readStateIndex:21358; appliedIndex:21355; }","duration":"125.555584ms","start":"2026-06-02T22:12:27.771276Z","end":"2026-06-02T22:12:27.896831Z","steps":["trace[1072731536] 'read index received' (duration: 12.154452ms)","trace[1072731536] 'applied index is now lower than readState.Index' (duration: 113.399882ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:12:27.897058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.76001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:12:27.897166Z","caller":"traceutil/trace.go:171","msg":"trace[1553003279] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20663; }","duration":"125.881663ms","start":"2026-06-02T22:12:27.771255Z","end":"2026-06-02T22:12:27.897137Z","steps":["trace[1553003279] 'agreement among raft nodes before linearized reading' (duration: 125.658017ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:08.878734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20234} {"level":"info","ts":"2026-06-02T22:16:09.005005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20234,"took":"121.244033ms","hash":1818412825,"current-db-size-bytes":58224640,"current-db-size":"58 MB","current-db-size-in-use-bytes":30846976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T22:16:09.005075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1818412825,"revision":20234,"compact-revision":17757} {"level":"warn","ts":"2026-06-02T22:16:28.65718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.795824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:16:28.657286Z","caller":"traceutil/trace.go:171","msg":"trace[697106660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23115; }","duration":"210.980408ms","start":"2026-06-02T22:16:28.446284Z","end":"2026-06-02T22:16:28.657264Z","steps":["trace[697106660] 'range keys from in-memory index tree' (duration: 210.687252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:28.897856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.344261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:16:28.89806Z","caller":"traceutil/trace.go:171","msg":"trace[697585171] linearizableReadLoop","detail":"{readStateIndex:23888; appliedIndex:23887; }","duration":"153.090918ms","start":"2026-06-02T22:16:28.744951Z","end":"2026-06-02T22:16:28.898042Z","steps":["trace[697585171] 'read index received' (duration: 22.313506ms)","trace[697585171] 'applied index is now lower than readState.Index' (duration: 130.775772ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:28.898306Z","caller":"traceutil/trace.go:171","msg":"trace[1151997774] transaction","detail":"{read_only:false; response_revision:23117; number_of_response:1; }","duration":"168.61126ms","start":"2026-06-02T22:16:28.72966Z","end":"2026-06-02T22:16:28.898271Z","steps":["trace[1151997774] 'process raft request' (duration: 37.718466ms)","trace[1151997774] 'compare' (duration: 130.101685ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:16:28.898434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.423966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bda87655-d034-422d-bca1-99fa4b543e6c\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-06-02T22:16:28.898486Z","caller":"traceutil/trace.go:171","msg":"trace[176479424] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bda87655-d034-422d-bca1-99fa4b543e6c; range_end:; response_count:1; response_revision:23117; }","duration":"153.563509ms","start":"2026-06-02T22:16:28.74491Z","end":"2026-06-02T22:16:28.898473Z","steps":["trace[176479424] 'agreement among raft nodes before linearized reading' (duration: 153.295553ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:55.003544Z","caller":"traceutil/trace.go:171","msg":"trace[99654950] linearizableReadLoop","detail":"{readStateIndex:26019; appliedIndex:26018; }","duration":"146.216393ms","start":"2026-06-02T22:19:54.857294Z","end":"2026-06-02T22:19:55.003511Z","steps":["trace[99654950] 'read index received' (duration: 145.886105ms)","trace[99654950] 'applied index is now lower than readState.Index' (duration: 328.748µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:55.003618Z","caller":"traceutil/trace.go:171","msg":"trace[503954443] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"269.648488ms","start":"2026-06-02T22:19:54.733927Z","end":"2026-06-02T22:19:55.003575Z","steps":["trace[503954443] 'process raft request' (duration: 269.359281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:55.004229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.885379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11793"} {"level":"info","ts":"2026-06-02T22:19:55.004361Z","caller":"traceutil/trace.go:171","msg":"trace[346783805] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25196; }","duration":"147.102784ms","start":"2026-06-02T22:19:54.857233Z","end":"2026-06-02T22:19:55.004336Z","steps":["trace[346783805] 'agreement among raft nodes before linearized reading' (duration: 146.428988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:55.284188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.259456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:19:55.284441Z","caller":"traceutil/trace.go:171","msg":"trace[926280628] linearizableReadLoop","detail":"{readStateIndex:26021; appliedIndex:26019; }","duration":"111.671113ms","start":"2026-06-02T22:19:55.172749Z","end":"2026-06-02T22:19:55.28442Z","steps":["trace[926280628] 'read index received' (duration: 55.225367ms)","trace[926280628] 'applied index is now lower than readState.Index' (duration: 56.444396ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:55.284587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.832866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:19:55.284618Z","caller":"traceutil/trace.go:171","msg":"trace[1148662001] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25198; }","duration":"111.917128ms","start":"2026-06-02T22:19:55.172691Z","end":"2026-06-02T22:19:55.284608Z","steps":["trace[1148662001] 'agreement among raft nodes before linearized reading' (duration: 111.804785ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:55.284625Z","caller":"traceutil/trace.go:171","msg":"trace[836971831] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"259.852542ms","start":"2026-06-02T22:19:55.024733Z","end":"2026-06-02T22:19:55.284586Z","steps":["trace[836971831] 'process raft request' (duration: 127.089163ms)","trace[836971831] 'compare' (duration: 132.076142ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:55.28489Z","caller":"traceutil/trace.go:171","msg":"trace[116806788] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"158.747804ms","start":"2026-06-02T22:19:55.12609Z","end":"2026-06-02T22:19:55.284838Z","steps":["trace[116806788] 'process raft request' (duration: 158.238432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:55.55896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.001773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:55.559093Z","caller":"traceutil/trace.go:171","msg":"trace[1006472821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25198; }","duration":"110.195617ms","start":"2026-06-02T22:19:55.44887Z","end":"2026-06-02T22:19:55.559066Z","steps":["trace[1006472821] 'range keys from in-memory index tree' (duration: 109.89671ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:56.033256Z","caller":"traceutil/trace.go:171","msg":"trace[2050874141] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"198.103549ms","start":"2026-06-02T22:19:55.835125Z","end":"2026-06-02T22:19:56.033229Z","steps":["trace[2050874141] 'process raft request' (duration: 197.931225ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:56.480821Z","caller":"traceutil/trace.go:171","msg":"trace[1884942495] linearizableReadLoop","detail":"{readStateIndex:26024; appliedIndex:26021; }","duration":"566.843316ms","start":"2026-06-02T22:19:55.913947Z","end":"2026-06-02T22:19:56.48079Z","steps":["trace[1884942495] 'read index received' (duration: 119.045179ms)","trace[1884942495] 'applied index is now lower than readState.Index' (duration: 447.796847ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:56.480943Z","caller":"traceutil/trace.go:171","msg":"trace[895288951] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"626.755346ms","start":"2026-06-02T22:19:55.854126Z","end":"2026-06-02T22:19:56.480881Z","steps":["trace[895288951] 'process raft request' (duration: 626.303385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.481113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.134922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-02T22:19:56.481164Z","caller":"traceutil/trace.go:171","msg":"trace[929212228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25202; }","duration":"567.252745ms","start":"2026-06-02T22:19:55.9139Z","end":"2026-06-02T22:19:56.481152Z","steps":["trace[929212228] 'agreement among raft nodes before linearized reading' (duration: 567.04431ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:56.481113Z","caller":"traceutil/trace.go:171","msg":"trace[2147293575] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"478.845632ms","start":"2026-06-02T22:19:56.002096Z","end":"2026-06-02T22:19:56.480942Z","steps":["trace[2147293575] 'process raft request' (duration: 478.642137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.481209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:55.913879Z","time spent":"567.321407ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T22:19:56.481293Z","caller":"traceutil/trace.go:171","msg":"trace[1435490127] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"625.785022ms","start":"2026-06-02T22:19:55.855466Z","end":"2026-06-02T22:19:56.481251Z","steps":["trace[1435490127] 'process raft request' (duration: 625.221028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.481388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:56.002078Z","time spent":"479.17471ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:19:56.481172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:55.854087Z","time spent":"626.96237ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:19:56.481398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:55.85545Z","time spent":"625.884874ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:19:56.524244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.008677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:19:56.524312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.876904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:19:56.524381Z","caller":"traceutil/trace.go:171","msg":"trace[1191231518] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25203; }","duration":"263.026177ms","start":"2026-06-02T22:19:56.261337Z","end":"2026-06-02T22:19:56.524363Z","steps":["trace[1191231518] 'agreement among raft nodes before linearized reading' (duration: 262.805322ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:56.524384Z","caller":"traceutil/trace.go:171","msg":"trace[232360950] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25203; }","duration":"529.179281ms","start":"2026-06-02T22:19:55.995174Z","end":"2026-06-02T22:19:56.524354Z","steps":["trace[232360950] 'agreement among raft nodes before linearized reading' (duration: 528.929395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.524392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.481443ms","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-06-02T22:19:56.524392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.626114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T22:19:56.524447Z","caller":"traceutil/trace.go:171","msg":"trace[1483426201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25203; }","duration":"438.587385ms","start":"2026-06-02T22:19:56.085843Z","end":"2026-06-02T22:19:56.52443Z","steps":["trace[1483426201] 'agreement among raft nodes before linearized reading' (duration: 438.442481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.524405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.050716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:56.524449Z","caller":"traceutil/trace.go:171","msg":"trace[1685933111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25203; }","duration":"129.744927ms","start":"2026-06-02T22:19:56.394691Z","end":"2026-06-02T22:19:56.524436Z","steps":["trace[1685933111] 'agreement among raft nodes before linearized reading' (duration: 129.530751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.524475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:56.085819Z","time spent":"438.650857ms","remote":"127.0.0.1:40540","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-06-02T22:19:56.524436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:55.995147Z","time spent":"529.277483ms","remote":"127.0.0.1:59198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-06-02T22:19:56.524528Z","caller":"traceutil/trace.go:171","msg":"trace[1319582530] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25203; }","duration":"409.20765ms","start":"2026-06-02T22:19:56.11527Z","end":"2026-06-02T22:19:56.524477Z","steps":["trace[1319582530] 'agreement among raft nodes before linearized reading' (duration: 408.993254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.524603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:56.115247Z","time spent":"409.344874ms","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:19:56.832747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.496029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:19:56.833196Z","caller":"traceutil/trace.go:171","msg":"trace[1139167912] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"321.707588ms","start":"2026-06-02T22:19:56.511458Z","end":"2026-06-02T22:19:56.833166Z","steps":["trace[1139167912] 'process raft request' (duration: 154.680606ms)","trace[1139167912] 'compare' (duration: 166.264983ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:56.833268Z","caller":"traceutil/trace.go:171","msg":"trace[1053436794] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"303.795158ms","start":"2026-06-02T22:19:56.529465Z","end":"2026-06-02T22:19:56.83326Z","steps":["trace[1053436794] 'process raft request' (duration: 303.388369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.83329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:56.511433Z","time spent":"321.81872ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:19:56.833333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:56.529443Z","time spent":"303.863149ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:19:56.833485Z","caller":"traceutil/trace.go:171","msg":"trace[1486503457] linearizableReadLoop","detail":"{readStateIndex:26031; appliedIndex:26028; }","duration":"273.985861ms","start":"2026-06-02T22:19:56.559464Z","end":"2026-06-02T22:19:56.83345Z","steps":["trace[1486503457] 'read index received' (duration: 106.707133ms)","trace[1486503457] 'applied index is now lower than readState.Index' (duration: 167.275668ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:56.833518Z","caller":"traceutil/trace.go:171","msg":"trace[1653866729] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"303.343506ms","start":"2026-06-02T22:19:56.530161Z","end":"2026-06-02T22:19:56.833504Z","steps":["trace[1653866729] 'process raft request' (duration: 303.035589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.833639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:56.530153Z","time spent":"303.448078ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:19:56.83371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.968219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:56.833748Z","caller":"traceutil/trace.go:171","msg":"trace[966619607] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25208; }","duration":"204.059182ms","start":"2026-06-02T22:19:56.629678Z","end":"2026-06-02T22:19:56.833737Z","steps":["trace[966619607] 'agreement among raft nodes before linearized reading' (duration: 203.964409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.8339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.190004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-02T22:19:56.833958Z","caller":"traceutil/trace.go:171","msg":"trace[1954056264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25208; }","duration":"144.331657ms","start":"2026-06-02T22:19:56.689595Z","end":"2026-06-02T22:19:56.833926Z","steps":["trace[1954056264] 'agreement among raft nodes before linearized reading' (duration: 144.052081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.834223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.76851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:56.834259Z","caller":"traceutil/trace.go:171","msg":"trace[430973078] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25208; }","duration":"274.844752ms","start":"2026-06-02T22:19:56.559403Z","end":"2026-06-02T22:19:56.834248Z","steps":["trace[430973078] 'agreement among raft nodes before linearized reading' (duration: 274.75792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:57.165993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.925361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:19:57.166272Z","caller":"traceutil/trace.go:171","msg":"trace[76469444] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"155.233749ms","start":"2026-06-02T22:19:57.011019Z","end":"2026-06-02T22:19:57.166253Z","steps":["trace[76469444] 'process raft request' (duration: 155.091906ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:57.166366Z","caller":"traceutil/trace.go:171","msg":"trace[231703633] linearizableReadLoop","detail":"{readStateIndex:26032; appliedIndex:26031; }","duration":"307.139939ms","start":"2026-06-02T22:19:56.859202Z","end":"2026-06-02T22:19:57.166342Z","steps":["trace[231703633] 'read index received' (duration: 131.853437ms)","trace[231703633] 'applied index is now lower than readState.Index' (duration: 175.283392ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:57.166365Z","caller":"traceutil/trace.go:171","msg":"trace[517057542] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"326.211217ms","start":"2026-06-02T22:19:56.840102Z","end":"2026-06-02T22:19:57.166313Z","steps":["trace[517057542] 'process raft request' (duration: 150.854364ms)","trace[517057542] 'compare' (duration: 174.732647ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:57.166672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.453706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:19:57.166757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:56.840073Z","time spent":"326.38278ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:19:57.166768Z","caller":"traceutil/trace.go:171","msg":"trace[86767164] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25210; }","duration":"307.62932ms","start":"2026-06-02T22:19:56.859115Z","end":"2026-06-02T22:19:57.166744Z","steps":["trace[86767164] 'agreement among raft nodes before linearized reading' (duration: 307.473386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:57.166817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:56.859087Z","time spent":"307.715662ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:19:57.440348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.597512ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-02T22:19:57.440654Z","caller":"traceutil/trace.go:171","msg":"trace[571982434] linearizableReadLoop","detail":"{readStateIndex:26034; appliedIndex:26033; }","duration":"232.658528ms","start":"2026-06-02T22:19:57.207935Z","end":"2026-06-02T22:19:57.440593Z","steps":["trace[571982434] 'read index received' (duration: 80.645586ms)","trace[571982434] 'applied index is now lower than readState.Index' (duration: 152.009772ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:57.440782Z","caller":"traceutil/trace.go:171","msg":"trace[973780583] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"148.326643ms","start":"2026-06-02T22:19:57.292404Z","end":"2026-06-02T22:19:57.440731Z","steps":["trace[973780583] 'process raft request' (duration: 148.130458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:57.440914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.943935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:19:57.440957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.466535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-02T22:19:57.440989Z","caller":"traceutil/trace.go:171","msg":"trace[2057727956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25211; }","duration":"150.545827ms","start":"2026-06-02T22:19:57.290435Z","end":"2026-06-02T22:19:57.44098Z","steps":["trace[2057727956] 'agreement among raft nodes before linearized reading' (duration: 150.356492ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:57.441Z","caller":"traceutil/trace.go:171","msg":"trace[409571033] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25211; }","duration":"233.092929ms","start":"2026-06-02T22:19:57.207886Z","end":"2026-06-02T22:19:57.440979Z","steps":["trace[409571033] 'agreement among raft nodes before linearized reading' (duration: 232.864364ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:57.618236Z","caller":"traceutil/trace.go:171","msg":"trace[1639808273] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"167.995215ms","start":"2026-06-02T22:19:57.450162Z","end":"2026-06-02T22:19:57.618157Z","steps":["trace[1639808273] 'process raft request' (duration: 167.75998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:58.289808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.739847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:19:58.289972Z","caller":"traceutil/trace.go:171","msg":"trace[343361160] linearizableReadLoop","detail":"{readStateIndex:26038; appliedIndex:26037; }","duration":"235.232101ms","start":"2026-06-02T22:19:58.054717Z","end":"2026-06-02T22:19:58.289949Z","steps":["trace[343361160] 'read index received' (duration: 94.982342ms)","trace[343361160] 'applied index is now lower than readState.Index' (duration: 140.247429ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:58.29001Z","caller":"traceutil/trace.go:171","msg":"trace[870739230] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"250.904417ms","start":"2026-06-02T22:19:58.039055Z","end":"2026-06-02T22:19:58.28996Z","steps":["trace[870739230] 'process raft request' (duration: 110.682489ms)","trace[870739230] 'compare' (duration: 139.169892ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:58.290129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.417644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:58.290175Z","caller":"traceutil/trace.go:171","msg":"trace[523802658] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25214; }","duration":"235.509157ms","start":"2026-06-02T22:19:58.054653Z","end":"2026-06-02T22:19:58.290162Z","steps":["trace[523802658] 'agreement among raft nodes before linearized reading' (duration: 235.408984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:58.291545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.25663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-02T22:19:58.291624Z","caller":"traceutil/trace.go:171","msg":"trace[1692802694] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25214; }","duration":"180.362742ms","start":"2026-06-02T22:19:58.111223Z","end":"2026-06-02T22:19:58.291585Z","steps":["trace[1692802694] 'agreement among raft nodes before linearized reading' (duration: 179.300787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:58.496903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.075634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-06-02T22:19:58.497659Z","caller":"traceutil/trace.go:171","msg":"trace[1984553215] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25214; }","duration":"100.871604ms","start":"2026-06-02T22:19:58.396766Z","end":"2026-06-02T22:19:58.497637Z","steps":["trace[1984553215] 'range keys from in-memory index tree' (duration: 99.858609ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:58.64928Z","caller":"traceutil/trace.go:171","msg":"trace[320639592] linearizableReadLoop","detail":"{readStateIndex:26043; appliedIndex:26039; }","duration":"118.512546ms","start":"2026-06-02T22:19:58.530729Z","end":"2026-06-02T22:19:58.649242Z","steps":["trace[320639592] 'read index received' (duration: 95.674647ms)","trace[320639592] 'applied index is now lower than readState.Index' (duration: 22.835749ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:58.649408Z","caller":"traceutil/trace.go:171","msg":"trace[329341711] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"147.855932ms","start":"2026-06-02T22:19:58.501542Z","end":"2026-06-02T22:19:58.649398Z","steps":["trace[329341711] 'process raft request' (duration: 147.475273ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:58.649544Z","caller":"traceutil/trace.go:171","msg":"trace[672769597] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"120.064154ms","start":"2026-06-02T22:19:58.529432Z","end":"2026-06-02T22:19:58.649496Z","steps":["trace[672769597] 'process raft request' (duration: 119.645374ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:58.649608Z","caller":"traceutil/trace.go:171","msg":"trace[1271984712] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"160.400952ms","start":"2026-06-02T22:19:58.489194Z","end":"2026-06-02T22:19:58.649595Z","steps":["trace[1271984712] 'process raft request' (duration: 159.733566ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:58.649311Z","caller":"traceutil/trace.go:171","msg":"trace[1792371459] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"161.737355ms","start":"2026-06-02T22:19:58.487524Z","end":"2026-06-02T22:19:58.649262Z","steps":["trace[1792371459] 'process raft request' (duration: 138.860226ms)","trace[1792371459] 'compare' (duration: 22.312526ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:58.649809Z","caller":"traceutil/trace.go:171","msg":"trace[1776814672] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"118.761242ms","start":"2026-06-02T22:19:58.531039Z","end":"2026-06-02T22:19:58.6498Z","steps":["trace[1776814672] 'process raft request' (duration: 118.135557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:58.649976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.221923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-02T22:19:58.650071Z","caller":"traceutil/trace.go:171","msg":"trace[594396325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25220; }","duration":"119.371547ms","start":"2026-06-02T22:19:58.530675Z","end":"2026-06-02T22:19:58.650047Z","steps":["trace[594396325] 'agreement among raft nodes before linearized reading' (duration: 119.131151ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:58.973229Z","caller":"traceutil/trace.go:171","msg":"trace[1947921424] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"129.911541ms","start":"2026-06-02T22:19:58.843284Z","end":"2026-06-02T22:19:58.973195Z","steps":["trace[1947921424] 'process raft request' (duration: 129.431039ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:59.134944Z","caller":"traceutil/trace.go:171","msg":"trace[2127047261] linearizableReadLoop","detail":"{readStateIndex:26047; appliedIndex:26045; }","duration":"259.892002ms","start":"2026-06-02T22:19:58.875029Z","end":"2026-06-02T22:19:59.134921Z","steps":["trace[2127047261] 'read index received' (duration: 97.897502ms)","trace[2127047261] 'applied index is now lower than readState.Index' (duration: 161.99333ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:59.135076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.031735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:19:59.135107Z","caller":"traceutil/trace.go:171","msg":"trace[469391506] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25223; }","duration":"260.136287ms","start":"2026-06-02T22:19:58.874963Z","end":"2026-06-02T22:19:59.135099Z","steps":["trace[469391506] 'agreement among raft nodes before linearized reading' (duration: 260.037305ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:59.135279Z","caller":"traceutil/trace.go:171","msg":"trace[1325757475] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"289.034592ms","start":"2026-06-02T22:19:58.846201Z","end":"2026-06-02T22:19:59.135236Z","steps":["trace[1325757475] 'process raft request' (duration: 287.878125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:59.483765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.374519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:19:59.484169Z","caller":"traceutil/trace.go:171","msg":"trace[700005586] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"311.964893ms","start":"2026-06-02T22:19:59.172186Z","end":"2026-06-02T22:19:59.484151Z","steps":["trace[700005586] 'process raft request' (duration: 122.083132ms)","trace[700005586] 'compare' (duration: 189.150004ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:59.484228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:59.172167Z","time spent":"312.034575ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:19:59.484429Z","caller":"traceutil/trace.go:171","msg":"trace[1711129795] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"308.818518ms","start":"2026-06-02T22:19:59.175602Z","end":"2026-06-02T22:19:59.484421Z","steps":["trace[1711129795] 'process raft request' (duration: 308.292275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:59.484482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:59.175539Z","time spent":"308.91872ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:19:59.484568Z","caller":"traceutil/trace.go:171","msg":"trace[944655161] linearizableReadLoop","detail":"{readStateIndex:26049; appliedIndex:26047; }","duration":"217.963256ms","start":"2026-06-02T22:19:59.266597Z","end":"2026-06-02T22:19:59.48456Z","steps":["trace[944655161] 'read index received' (duration: 27.707546ms)","trace[944655161] 'applied index is now lower than readState.Index' (duration: 190.25455ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:59.48468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.081928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:59.484711Z","caller":"traceutil/trace.go:171","msg":"trace[1283528164] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25226; }","duration":"218.110659ms","start":"2026-06-02T22:19:59.266588Z","end":"2026-06-02T22:19:59.484698Z","steps":["trace[1283528164] 'agreement among raft nodes before linearized reading' (duration: 218.061798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:59.83793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.615055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:19:59.838026Z","caller":"traceutil/trace.go:171","msg":"trace[1448469390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25226; }","duration":"214.752118ms","start":"2026-06-02T22:19:59.623253Z","end":"2026-06-02T22:19:59.838005Z","steps":["trace[1448469390] 'range keys from in-memory index tree' (duration: 214.435711ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:00.397781Z","caller":"traceutil/trace.go:171","msg":"trace[34834543] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"291.948112ms","start":"2026-06-02T22:20:00.105797Z","end":"2026-06-02T22:20:00.397745Z","steps":["trace[34834543] 'process raft request' (duration: 291.753318ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:00.442275Z","caller":"traceutil/trace.go:171","msg":"trace[12540372] linearizableReadLoop","detail":"{readStateIndex:26053; appliedIndex:26051; }","duration":"124.495371ms","start":"2026-06-02T22:20:00.317745Z","end":"2026-06-02T22:20:00.44224Z","steps":["trace[12540372] 'read index received' (duration: 79.838248ms)","trace[12540372] 'applied index is now lower than readState.Index' (duration: 44.655352ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:00.4424Z","caller":"traceutil/trace.go:171","msg":"trace[1703790442] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"136.834347ms","start":"2026-06-02T22:20:00.305514Z","end":"2026-06-02T22:20:00.442348Z","steps":["trace[1703790442] 'process raft request' (duration: 136.489999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:00.442649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.884589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-06-02T22:20:00.442785Z","caller":"traceutil/trace.go:171","msg":"trace[1337083037] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25229; }","duration":"125.085034ms","start":"2026-06-02T22:20:00.317674Z","end":"2026-06-02T22:20:00.442759Z","steps":["trace[1337083037] 'agreement among raft nodes before linearized reading' (duration: 124.761106ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:00.802913Z","caller":"traceutil/trace.go:171","msg":"trace[1823922250] linearizableReadLoop","detail":"{readStateIndex:26055; appliedIndex:26054; }","duration":"360.518081ms","start":"2026-06-02T22:20:00.442361Z","end":"2026-06-02T22:20:00.802879Z","steps":["trace[1823922250] 'read index received' (duration: 360.359227ms)","trace[1823922250] 'applied index is now lower than readState.Index' (duration: 157.594µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:00.803065Z","caller":"traceutil/trace.go:171","msg":"trace[543457315] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"394.63992ms","start":"2026-06-02T22:20:00.408378Z","end":"2026-06-02T22:20:00.803018Z","steps":["trace[543457315] 'process raft request' (duration: 394.300272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:00.803163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.261037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:194"} {"level":"warn","ts":"2026-06-02T22:20:00.803119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.404727Z","time spent":"398.374229ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-02T22:20:00.803209Z","caller":"traceutil/trace.go:171","msg":"trace[686339631] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:25230; }","duration":"398.362259ms","start":"2026-06-02T22:20:00.404832Z","end":"2026-06-02T22:20:00.803195Z","steps":["trace[686339631] 'agreement among raft nodes before linearized reading' (duration: 398.192855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:00.803246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.40481Z","time spent":"398.42757ms","remote":"127.0.0.1:40480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-06-02T22:20:00.803257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.408355Z","time spent":"394.802503ms","remote":"127.0.0.1:40516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:01.065148Z","caller":"traceutil/trace.go:171","msg":"trace[2026795894] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"614.91034ms","start":"2026-06-02T22:20:00.450201Z","end":"2026-06-02T22:20:01.065111Z","steps":["trace[2026795894] 'process raft request' (duration: 614.608473ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.06516Z","caller":"traceutil/trace.go:171","msg":"trace[1689850133] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"561.633071ms","start":"2026-06-02T22:20:00.503469Z","end":"2026-06-02T22:20:01.065103Z","steps":["trace[1689850133] 'process raft request' (duration: 561.554369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.065322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.450154Z","time spent":"615.072614ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:01.065384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.503427Z","time spent":"561.817095ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:01.065468Z","caller":"traceutil/trace.go:171","msg":"trace[2095801281] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"408.338049ms","start":"2026-06-02T22:20:00.657101Z","end":"2026-06-02T22:20:01.065439Z","steps":["trace[2095801281] 'process raft request' (duration: 407.956649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.065631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.657069Z","time spent":"408.475432ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:01.065667Z","caller":"traceutil/trace.go:171","msg":"trace[1797247092] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"407.379147ms","start":"2026-06-02T22:20:00.658255Z","end":"2026-06-02T22:20:01.065634Z","steps":["trace[1797247092] 'process raft request' (duration: 407.082669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.065851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.658176Z","time spent":"407.58043ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:01.065996Z","caller":"traceutil/trace.go:171","msg":"trace[1034018527] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"407.644573ms","start":"2026-06-02T22:20:00.658343Z","end":"2026-06-02T22:20:01.065987Z","steps":["trace[1034018527] 'process raft request' (duration: 407.217853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.066036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.658331Z","time spent":"407.685794ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:01.066078Z","caller":"traceutil/trace.go:171","msg":"trace[2123171553] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"406.33269ms","start":"2026-06-02T22:20:00.659722Z","end":"2026-06-02T22:20:01.066054Z","steps":["trace[2123171553] 'process raft request' (duration: 406.132246ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.066156Z","caller":"traceutil/trace.go:171","msg":"trace[1481081455] linearizableReadLoop","detail":"{readStateIndex:26062; appliedIndex:26055; }","duration":"263.132221ms","start":"2026-06-02T22:20:00.802999Z","end":"2026-06-02T22:20:01.066131Z","steps":["trace[1481081455] 'read index received' (duration: 261.752828ms)","trace[1481081455] 'applied index is now lower than readState.Index' (duration: 1.358033ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:01.066305Z","caller":"traceutil/trace.go:171","msg":"trace[462174841] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"323.307447ms","start":"2026-06-02T22:20:00.742969Z","end":"2026-06-02T22:20:01.066277Z","steps":["trace[462174841] 'process raft request' (duration: 323.03266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.066361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.634249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-02T22:20:01.066364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.617543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:01.066392Z","caller":"traceutil/trace.go:171","msg":"trace[1850414533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25237; }","duration":"384.70958ms","start":"2026-06-02T22:20:00.681677Z","end":"2026-06-02T22:20:01.066386Z","steps":["trace[1850414533] 'agreement among raft nodes before linearized reading' (duration: 384.492335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.066396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.999011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"warn","ts":"2026-06-02T22:20:01.066425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.681655Z","time spent":"384.765632ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T22:20:01.066433Z","caller":"traceutil/trace.go:171","msg":"trace[964442746] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25237; }","duration":"497.660824ms","start":"2026-06-02T22:20:00.568733Z","end":"2026-06-02T22:20:01.066393Z","steps":["trace[964442746] 'agreement among raft nodes before linearized reading' (duration: 497.593323ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.066444Z","caller":"traceutil/trace.go:171","msg":"trace[788705756] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25237; }","duration":"448.114664ms","start":"2026-06-02T22:20:00.618317Z","end":"2026-06-02T22:20:01.066432Z","steps":["trace[788705756] 'agreement among raft nodes before linearized reading' (duration: 447.94177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.066435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.011359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:20:01.066465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.568654Z","time spent":"497.805018ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:20:01.066466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.618288Z","time spent":"448.172065ms","remote":"127.0.0.1:40478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-06-02T22:20:01.066391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.474907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:20:01.0665Z","caller":"traceutil/trace.go:171","msg":"trace[708971986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25237; }","duration":"619.149693ms","start":"2026-06-02T22:20:00.447335Z","end":"2026-06-02T22:20:01.066484Z","steps":["trace[708971986] 'agreement among raft nodes before linearized reading' (duration: 619.013249ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.066523Z","caller":"traceutil/trace.go:171","msg":"trace[751657813] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25237; }","duration":"536.665732ms","start":"2026-06-02T22:20:00.529839Z","end":"2026-06-02T22:20:01.066504Z","steps":["trace[751657813] 'agreement among raft nodes before linearized reading' (duration: 536.461157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.066544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.447309Z","time spent":"619.228384ms","remote":"127.0.0.1:40258","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-06-02T22:20:01.066566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.529813Z","time spent":"536.742023ms","remote":"127.0.0.1:40516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:20:01.066649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.742935Z","time spent":"323.44041ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:01.066307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.659696Z","time spent":"406.467974ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:01.35974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.007801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:01.36003Z","caller":"traceutil/trace.go:171","msg":"trace[310451181] transaction","detail":"{read_only:false; response_revision:25239; number_of_response:1; }","duration":"287.487086ms","start":"2026-06-02T22:20:01.072525Z","end":"2026-06-02T22:20:01.360012Z","steps":["trace[310451181] 'process raft request' (duration: 287.338222ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.360166Z","caller":"traceutil/trace.go:171","msg":"trace[467761697] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"555.28197ms","start":"2026-06-02T22:20:00.804849Z","end":"2026-06-02T22:20:01.360131Z","steps":["trace[467761697] 'process raft request' (duration: 365.771377ms)","trace[467761697] 'compare' (duration: 188.752604ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:01.360246Z","caller":"traceutil/trace.go:171","msg":"trace[1063852604] linearizableReadLoop","detail":"{readStateIndex:26063; appliedIndex:26062; }","duration":"294.007822ms","start":"2026-06-02T22:20:01.066227Z","end":"2026-06-02T22:20:01.360235Z","steps":["trace[1063852604] 'read index received' (duration: 104.391717ms)","trace[1063852604] 'applied index is now lower than readState.Index' (duration: 189.615015ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:01.360286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.804826Z","time spent":"555.399002ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T22:20:01.360424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.340375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-02T22:20:01.360444Z","caller":"traceutil/trace.go:171","msg":"trace[1876345272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25239; }","duration":"382.416497ms","start":"2026-06-02T22:20:00.978021Z","end":"2026-06-02T22:20:01.360437Z","steps":["trace[1876345272] 'agreement among raft nodes before linearized reading' (duration: 382.289804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.360472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.977998Z","time spent":"382.466207ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T22:20:01.360677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.824702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:01.3607Z","caller":"traceutil/trace.go:171","msg":"trace[516685066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25239; }","duration":"289.871454ms","start":"2026-06-02T22:20:01.070823Z","end":"2026-06-02T22:20:01.360694Z","steps":["trace[516685066] 'agreement among raft nodes before linearized reading' (duration: 289.809992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.360758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.06529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:20:01.360806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.003943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:194"} {"level":"info","ts":"2026-06-02T22:20:01.360849Z","caller":"traceutil/trace.go:171","msg":"trace[175041897] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:25239; }","duration":"553.068405ms","start":"2026-06-02T22:20:00.807767Z","end":"2026-06-02T22:20:01.360835Z","steps":["trace[175041897] 'agreement among raft nodes before linearized reading' (duration: 552.943362ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.360839Z","caller":"traceutil/trace.go:171","msg":"trace[1369947980] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25239; }","duration":"169.221805ms","start":"2026-06-02T22:20:01.191598Z","end":"2026-06-02T22:20:01.36082Z","steps":["trace[1369947980] 'agreement among raft nodes before linearized reading' (duration: 169.056481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.360877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:00.807738Z","time spent":"553.131736ms","remote":"127.0.0.1:40480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-06-02T22:20:01.360869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.539501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T22:20:01.360948Z","caller":"traceutil/trace.go:171","msg":"trace[2053515540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25239; }","duration":"221.679395ms","start":"2026-06-02T22:20:01.139248Z","end":"2026-06-02T22:20:01.360928Z","steps":["trace[2053515540] 'agreement among raft nodes before linearized reading' (duration: 221.44231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.36101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.133829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra-registry\" ","response":"range_response_count:1 size:784"} {"level":"info","ts":"2026-06-02T22:20:01.361035Z","caller":"traceutil/trace.go:171","msg":"trace[2066028123] range","detail":"{range_begin:/registry/ingress/openstack/container-infra-registry; range_end:; response_count:1; response_revision:25239; }","duration":"290.173291ms","start":"2026-06-02T22:20:01.070854Z","end":"2026-06-02T22:20:01.361028Z","steps":["trace[2066028123] 'agreement among raft nodes before linearized reading' (duration: 289.853263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.361019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.099649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra\" ","response":"range_response_count:1 size:729"} {"level":"info","ts":"2026-06-02T22:20:01.361108Z","caller":"traceutil/trace.go:171","msg":"trace[418602419] range","detail":"{range_begin:/registry/ingress/openstack/container-infra; range_end:; response_count:1; response_revision:25239; }","duration":"290.212111ms","start":"2026-06-02T22:20:01.070874Z","end":"2026-06-02T22:20:01.361086Z","steps":["trace[418602419] 'agreement among raft nodes before linearized reading' (duration: 289.810782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.362661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.34223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-02T22:20:01.36272Z","caller":"traceutil/trace.go:171","msg":"trace[1478760548] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25239; }","duration":"162.462153ms","start":"2026-06-02T22:20:01.200246Z","end":"2026-06-02T22:20:01.362708Z","steps":["trace[1478760548] 'agreement among raft nodes before linearized reading' (duration: 160.484535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.829689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.290115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:01.829826Z","caller":"traceutil/trace.go:171","msg":"trace[2105860372] transaction","detail":"{read_only:false; response_revision:25240; number_of_response:1; }","duration":"462.189652ms","start":"2026-06-02T22:20:01.367612Z","end":"2026-06-02T22:20:01.829802Z","steps":["trace[2105860372] 'process raft request' (duration: 210.685041ms)","trace[2105860372] 'compare' (duration: 251.07344ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:01.830171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.367589Z","time spent":"462.274164ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:01.924536Z","caller":"traceutil/trace.go:171","msg":"trace[83868897] transaction","detail":"{read_only:false; response_revision:25241; number_of_response:1; }","duration":"556.138158ms","start":"2026-06-02T22:20:01.368358Z","end":"2026-06-02T22:20:01.924496Z","steps":["trace[83868897] 'process raft request' (duration: 555.835361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.924835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.368349Z","time spent":"556.365314ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:01.924976Z","caller":"traceutil/trace.go:171","msg":"trace[689637651] transaction","detail":"{read_only:false; response_revision:25242; number_of_response:1; }","duration":"556.317953ms","start":"2026-06-02T22:20:01.368619Z","end":"2026-06-02T22:20:01.924937Z","steps":["trace[689637651] 'process raft request' (duration: 555.7921ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.925031Z","caller":"traceutil/trace.go:171","msg":"trace[65318572] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"556.275191ms","start":"2026-06-02T22:20:01.36871Z","end":"2026-06-02T22:20:01.924985Z","steps":["trace[65318572] 'process raft request' (duration: 556.042125ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.925103Z","caller":"traceutil/trace.go:171","msg":"trace[835457406] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"552.243144ms","start":"2026-06-02T22:20:01.372805Z","end":"2026-06-02T22:20:01.925048Z","steps":["trace[835457406] 'process raft request' (duration: 552.101081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.92518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.368612Z","time spent":"556.464606ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:01.925261Z","caller":"traceutil/trace.go:171","msg":"trace[1507125920] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"434.904346ms","start":"2026-06-02T22:20:01.490336Z","end":"2026-06-02T22:20:01.925241Z","steps":["trace[1507125920] 'process raft request' (duration: 434.63085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.925272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.368703Z","time spent":"556.452316ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:01.925293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.37278Z","time spent":"552.421049ms","remote":"127.0.0.1:40476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5614,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T22:20:01.925112Z","caller":"traceutil/trace.go:171","msg":"trace[1050773305] linearizableReadLoop","detail":"{readStateIndex:26069; appliedIndex:26064; }","duration":"435.093431ms","start":"2026-06-02T22:20:01.489991Z","end":"2026-06-02T22:20:01.925084Z","steps":["trace[1050773305] 'read index received' (duration: 88.22327ms)","trace[1050773305] 'applied index is now lower than readState.Index' (duration: 346.868801ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:01.925375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.490318Z","time spent":"434.993919ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:01.925388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.369858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-02T22:20:01.925445Z","caller":"traceutil/trace.go:171","msg":"trace[284531574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25246; }","duration":"435.499271ms","start":"2026-06-02T22:20:01.489929Z","end":"2026-06-02T22:20:01.925429Z","steps":["trace[284531574] 'agreement among raft nodes before linearized reading' (duration: 435.242684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.925493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.489901Z","time spent":"435.583704ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T22:20:01.925577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.82502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-06-02T22:20:01.925513Z","caller":"traceutil/trace.go:171","msg":"trace[1818715193] transaction","detail":"{read_only:false; response_revision:25246; number_of_response:1; }","duration":"434.345423ms","start":"2026-06-02T22:20:01.491056Z","end":"2026-06-02T22:20:01.925401Z","steps":["trace[1818715193] 'process raft request' (duration: 433.976354ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.92564Z","caller":"traceutil/trace.go:171","msg":"trace[1022167037] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25246; }","duration":"246.914612ms","start":"2026-06-02T22:20:01.678705Z","end":"2026-06-02T22:20:01.92562Z","steps":["trace[1022167037] 'agreement among raft nodes before linearized reading' (duration: 246.733108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:01.926996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.491023Z","time spent":"435.779288ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:02.142265Z","caller":"traceutil/trace.go:171","msg":"trace[704921097] linearizableReadLoop","detail":"{readStateIndex:26072; appliedIndex:26071; }","duration":"212.004663ms","start":"2026-06-02T22:20:01.930227Z","end":"2026-06-02T22:20:02.142231Z","steps":["trace[704921097] 'read index received' (duration: 139.204174ms)","trace[704921097] 'applied index is now lower than readState.Index' (duration: 72.794629ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:02.142414Z","caller":"traceutil/trace.go:171","msg":"trace[273194393] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"213.979689ms","start":"2026-06-02T22:20:01.928393Z","end":"2026-06-02T22:20:02.142373Z","steps":["trace[273194393] 'process raft request' (duration: 140.685519ms)","trace[273194393] 'compare' (duration: 72.926572ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:02.142594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.342871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b223a487-cf61-49bb-a4a6-c81dca5af849\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-06-02T22:20:02.142642Z","caller":"traceutil/trace.go:171","msg":"trace[1388207305] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b223a487-cf61-49bb-a4a6-c81dca5af849; range_end:; response_count:1; response_revision:25247; }","duration":"212.456393ms","start":"2026-06-02T22:20:01.930172Z","end":"2026-06-02T22:20:02.142628Z","steps":["trace[1388207305] 'agreement among raft nodes before linearized reading' (duration: 212.212758ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.21209Z","caller":"traceutil/trace.go:171","msg":"trace[233079479] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"280.979949ms","start":"2026-06-02T22:20:01.931075Z","end":"2026-06-02T22:20:02.212055Z","steps":["trace[233079479] 'process raft request' (duration: 280.637951ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.21215Z","caller":"traceutil/trace.go:171","msg":"trace[1135958087] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"280.843016ms","start":"2026-06-02T22:20:01.931273Z","end":"2026-06-02T22:20:02.212116Z","steps":["trace[1135958087] 'process raft request' (duration: 280.684532ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.2126Z","caller":"traceutil/trace.go:171","msg":"trace[522231008] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"280.423116ms","start":"2026-06-02T22:20:01.932162Z","end":"2026-06-02T22:20:02.212586Z","steps":["trace[522231008] 'process raft request' (duration: 279.879993ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.212839Z","caller":"traceutil/trace.go:171","msg":"trace[1422026389] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"279.75957ms","start":"2026-06-02T22:20:01.933066Z","end":"2026-06-02T22:20:02.212825Z","steps":["trace[1422026389] 'process raft request' (duration: 279.343301ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.212835Z","caller":"traceutil/trace.go:171","msg":"trace[37847794] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"279.241048ms","start":"2026-06-02T22:20:01.933564Z","end":"2026-06-02T22:20:02.212805Z","steps":["trace[37847794] 'process raft request' (duration: 279.006832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:02.213218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.621899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-02T22:20:02.213264Z","caller":"traceutil/trace.go:171","msg":"trace[1560839473] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:25252; }","duration":"230.684821ms","start":"2026-06-02T22:20:01.982566Z","end":"2026-06-02T22:20:02.213251Z","steps":["trace[1560839473] 'agreement among raft nodes before linearized reading' (duration: 230.519867ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:06.04742Z","caller":"traceutil/trace.go:171","msg":"trace[2138544682] linearizableReadLoop","detail":"{readStateIndex:26112; appliedIndex:26111; }","duration":"108.297243ms","start":"2026-06-02T22:20:05.93907Z","end":"2026-06-02T22:20:06.047367Z","steps":["trace[2138544682] 'read index received' (duration: 108.15079ms)","trace[2138544682] 'applied index is now lower than readState.Index' (duration: 145.233µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:06.047458Z","caller":"traceutil/trace.go:171","msg":"trace[705379326] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"109.343617ms","start":"2026-06-02T22:20:05.938074Z","end":"2026-06-02T22:20:06.047418Z","steps":["trace[705379326] 'process raft request' (duration: 109.078601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.491653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.552154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-02T22:20:06.49177Z","caller":"traceutil/trace.go:171","msg":"trace[1531013180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25286; }","duration":"552.722749ms","start":"2026-06-02T22:20:05.939004Z","end":"2026-06-02T22:20:06.491727Z","steps":["trace[1531013180] 'agreement among raft nodes before linearized reading' (duration: 108.550989ms)","trace[1531013180] 'range keys from in-memory index tree' (duration: 443.870822ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:06.491853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:05.938979Z","time spent":"552.830312ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T22:20:06.49243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.850407ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:06.49252Z","caller":"traceutil/trace.go:171","msg":"trace[2129888065] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25286; }","duration":"377.9444ms","start":"2026-06-02T22:20:06.114565Z","end":"2026-06-02T22:20:06.492509Z","steps":["trace[2129888065] 'range keys from in-memory index tree' (duration: 377.839567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.49525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.648294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:06.495329Z","caller":"traceutil/trace.go:171","msg":"trace[1109936296] linearizableReadLoop","detail":"{readStateIndex:26113; appliedIndex:26112; }","duration":"447.801747ms","start":"2026-06-02T22:20:06.047514Z","end":"2026-06-02T22:20:06.495316Z","steps":["trace[1109936296] 'read index received' (duration: 144.04388ms)","trace[1109936296] 'applied index is now lower than readState.Index' (duration: 303.756557ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:06.495565Z","caller":"traceutil/trace.go:171","msg":"trace[93500497] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"555.159156ms","start":"2026-06-02T22:20:05.940388Z","end":"2026-06-02T22:20:06.495547Z","steps":["trace[93500497] 'process raft request' (duration: 251.129383ms)","trace[93500497] 'compare' (duration: 301.488042ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:06.495633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:05.940353Z","time spent":"555.248049ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:06.495808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.378892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T22:20:06.495859Z","caller":"traceutil/trace.go:171","msg":"trace[1099222539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25287; }","duration":"555.474085ms","start":"2026-06-02T22:20:05.940375Z","end":"2026-06-02T22:20:06.495849Z","steps":["trace[1099222539] 'agreement among raft nodes before linearized reading' (duration: 555.355662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.495885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:05.940351Z","time spent":"555.527635ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T22:20:06.566019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.116407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-02T22:20:06.566084Z","caller":"traceutil/trace.go:171","msg":"trace[486487676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25288; }","duration":"337.23565ms","start":"2026-06-02T22:20:06.228829Z","end":"2026-06-02T22:20:06.566065Z","steps":["trace[486487676] 'agreement among raft nodes before linearized reading' (duration: 337.047666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.566115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.228803Z","time spent":"337.304572ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T22:20:06.566121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.088411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:20:06.566233Z","caller":"traceutil/trace.go:171","msg":"trace[907488143] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25288; }","duration":"256.292496ms","start":"2026-06-02T22:20:06.309918Z","end":"2026-06-02T22:20:06.56621Z","steps":["trace[907488143] 'agreement among raft nodes before linearized reading' (duration: 256.06338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.566247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.860082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-02T22:20:06.56634Z","caller":"traceutil/trace.go:171","msg":"trace[468213875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25288; }","duration":"337.017825ms","start":"2026-06-02T22:20:06.229305Z","end":"2026-06-02T22:20:06.566323Z","steps":["trace[468213875] 'agreement among raft nodes before linearized reading' (duration: 336.684597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.566384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.229275Z","time spent":"337.100977ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T22:20:06.907993Z","caller":"traceutil/trace.go:171","msg":"trace[2091798321] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"407.077117ms","start":"2026-06-02T22:20:06.500885Z","end":"2026-06-02T22:20:06.907962Z","steps":["trace[2091798321] 'process raft request' (duration: 407.008285ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:06.908082Z","caller":"traceutil/trace.go:171","msg":"trace[920817508] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"407.242311ms","start":"2026-06-02T22:20:06.5008Z","end":"2026-06-02T22:20:06.908043Z","steps":["trace[920817508] 'process raft request' (duration: 406.831441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.90822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.500876Z","time spent":"407.20661ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:06.908267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.500777Z","time spent":"407.373785ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:06.922646Z","caller":"traceutil/trace.go:171","msg":"trace[632578013] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"348.699215ms","start":"2026-06-02T22:20:06.573919Z","end":"2026-06-02T22:20:06.922619Z","steps":["trace[632578013] 'process raft request' (duration: 348.650174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.922846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.573909Z","time spent":"348.823488ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:06.922927Z","caller":"traceutil/trace.go:171","msg":"trace[1255658955] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"352.030475ms","start":"2026-06-02T22:20:06.570873Z","end":"2026-06-02T22:20:06.922904Z","steps":["trace[1255658955] 'process raft request' (duration: 351.519973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.922997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.570847Z","time spent":"352.116097ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:12.708432Z","caller":"traceutil/trace.go:171","msg":"trace[789460364] transaction","detail":"{read_only:false; response_revision:25336; number_of_response:1; }","duration":"161.189002ms","start":"2026-06-02T22:20:12.547216Z","end":"2026-06-02T22:20:12.708405Z","steps":["trace[789460364] 'process raft request' (duration: 161.026308ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:12.806915Z","caller":"traceutil/trace.go:171","msg":"trace[1608096062] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"217.141026ms","start":"2026-06-02T22:20:12.589744Z","end":"2026-06-02T22:20:12.806885Z","steps":["trace[1608096062] 'process raft request' (duration: 216.86943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.137046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.583569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-06-02T22:20:13.13713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.315634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T22:20:13.1372Z","caller":"traceutil/trace.go:171","msg":"trace[1753735870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25337; }","duration":"185.759372ms","start":"2026-06-02T22:20:12.95138Z","end":"2026-06-02T22:20:13.13714Z","steps":["trace[1753735870] 'range keys from in-memory index tree' (duration: 185.310142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.137314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.593212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:20:13.137235Z","caller":"traceutil/trace.go:171","msg":"trace[1962590029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25337; }","duration":"188.502019ms","start":"2026-06-02T22:20:12.948708Z","end":"2026-06-02T22:20:13.13721Z","steps":["trace[1962590029] 'range keys from in-memory index tree' (duration: 188.005946ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:13.137445Z","caller":"traceutil/trace.go:171","msg":"trace[98790696] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25337; }","duration":"129.746596ms","start":"2026-06-02T22:20:13.007671Z","end":"2026-06-02T22:20:13.137417Z","steps":["trace[98790696] 'count revisions from in-memory index tree' (duration: 129.456979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.137461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.348594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-02T22:20:13.137564Z","caller":"traceutil/trace.go:171","msg":"trace[1847129378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25337; }","duration":"188.509668ms","start":"2026-06-02T22:20:12.949023Z","end":"2026-06-02T22:20:13.137533Z","steps":["trace[1847129378] 'range keys from in-memory index tree' (duration: 188.120709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.138152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.66238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:20:13.138238Z","caller":"traceutil/trace.go:171","msg":"trace[1414083851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25337; }","duration":"175.780772ms","start":"2026-06-02T22:20:12.96244Z","end":"2026-06-02T22:20:13.138221Z","steps":["trace[1414083851] 'range keys from in-memory index tree' (duration: 175.081136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.605499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.302978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:13.606038Z","caller":"traceutil/trace.go:171","msg":"trace[559406481] linearizableReadLoop","detail":"{readStateIndex:26175; appliedIndex:26166; }","duration":"444.176479ms","start":"2026-06-02T22:20:13.16184Z","end":"2026-06-02T22:20:13.606017Z","steps":["trace[559406481] 'read index received' (duration: 179.314528ms)","trace[559406481] 'applied index is now lower than readState.Index' (duration: 264.861141ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:13.606158Z","caller":"traceutil/trace.go:171","msg":"trace[1543412398] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"461.873335ms","start":"2026-06-02T22:20:13.144272Z","end":"2026-06-02T22:20:13.606145Z","steps":["trace[1543412398] 'process raft request' (duration: 196.796628ms)","trace[1543412398] 'compare' (duration: 264.172396ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:13.606287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.14424Z","time spent":"462.002687ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:13.606307Z","caller":"traceutil/trace.go:171","msg":"trace[154590210] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"454.487057ms","start":"2026-06-02T22:20:13.151781Z","end":"2026-06-02T22:20:13.606268Z","steps":["trace[154590210] 'process raft request' (duration: 454.069067ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:13.606398Z","caller":"traceutil/trace.go:171","msg":"trace[1957757771] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"461.773842ms","start":"2026-06-02T22:20:13.1446Z","end":"2026-06-02T22:20:13.606373Z","steps":["trace[1957757771] 'process raft request' (duration: 461.166658ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:13.606469Z","caller":"traceutil/trace.go:171","msg":"trace[1497492433] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"462.041388ms","start":"2026-06-02T22:20:13.144418Z","end":"2026-06-02T22:20:13.60646Z","steps":["trace[1497492433] 'process raft request' (duration: 461.215079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.606506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.151761Z","time spent":"454.63365ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:13.606589Z","caller":"traceutil/trace.go:171","msg":"trace[241202121] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"453.010321ms","start":"2026-06-02T22:20:13.153562Z","end":"2026-06-02T22:20:13.606572Z","steps":["trace[241202121] 'process raft request' (duration: 452.326135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.606593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.14441Z","time spent":"462.096229ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:13.606582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.144593Z","time spent":"461.859924ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:13.60668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.153553Z","time spent":"453.078812ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:13.606324Z","caller":"traceutil/trace.go:171","msg":"trace[136329193] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"461.747631ms","start":"2026-06-02T22:20:13.144544Z","end":"2026-06-02T22:20:13.606292Z","steps":["trace[136329193] 'process raft request' (duration: 461.172378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.606779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.144533Z","time spent":"462.225813ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:13.606844Z","caller":"traceutil/trace.go:171","msg":"trace[1839883355] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"453.161684ms","start":"2026-06-02T22:20:13.153668Z","end":"2026-06-02T22:20:13.606829Z","steps":["trace[1839883355] 'process raft request' (duration: 452.266333ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:13.606845Z","caller":"traceutil/trace.go:171","msg":"trace[209560475] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"457.922729ms","start":"2026-06-02T22:20:13.1489Z","end":"2026-06-02T22:20:13.606822Z","steps":["trace[209560475] 'process raft request' (duration: 456.912315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.606932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.153662Z","time spent":"453.219725ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:13.607088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.148884Z","time spent":"458.117714ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:13.607155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.226205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-02T22:20:13.607206Z","caller":"traceutil/trace.go:171","msg":"trace[169399587] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25347; }","duration":"261.278207ms","start":"2026-06-02T22:20:13.345917Z","end":"2026-06-02T22:20:13.607195Z","steps":["trace[169399587] 'agreement among raft nodes before linearized reading' (duration: 260.962048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.607396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.457013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:20:13.607423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.682168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-02T22:20:13.607435Z","caller":"traceutil/trace.go:171","msg":"trace[1859578040] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25347; }","duration":"192.499104ms","start":"2026-06-02T22:20:13.414925Z","end":"2026-06-02T22:20:13.607424Z","steps":["trace[1859578040] 'agreement among raft nodes before linearized reading' (duration: 192.435603ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:13.607453Z","caller":"traceutil/trace.go:171","msg":"trace[1353047795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25347; }","duration":"167.74684ms","start":"2026-06-02T22:20:13.439697Z","end":"2026-06-02T22:20:13.607444Z","steps":["trace[1353047795] 'agreement among raft nodes before linearized reading' (duration: 167.611266ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:13.607572Z","caller":"traceutil/trace.go:171","msg":"trace[1736553377] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"453.74923ms","start":"2026-06-02T22:20:13.153814Z","end":"2026-06-02T22:20:13.607563Z","steps":["trace[1736553377] 'process raft request' (duration: 452.162261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.607621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.153796Z","time spent":"453.79914ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:13.607765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.915121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:20:13.607823Z","caller":"traceutil/trace.go:171","msg":"trace[548254225] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25347; }","duration":"446.009143ms","start":"2026-06-02T22:20:13.161799Z","end":"2026-06-02T22:20:13.607808Z","steps":["trace[548254225] 'agreement among raft nodes before linearized reading' (duration: 445.831879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.607864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.161783Z","time spent":"446.070924ms","remote":"127.0.0.1:40606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":99,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-06-02T22:20:13.864813Z","caller":"traceutil/trace.go:171","msg":"trace[920510783] linearizableReadLoop","detail":"{readStateIndex:26176; appliedIndex:26175; }","duration":"208.131059ms","start":"2026-06-02T22:20:13.656637Z","end":"2026-06-02T22:20:13.864768Z","steps":["trace[920510783] 'read index received' (duration: 155.227439ms)","trace[920510783] 'applied index is now lower than readState.Index' (duration: 52.90212ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:13.864976Z","caller":"traceutil/trace.go:171","msg":"trace[1241143240] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"252.490986ms","start":"2026-06-02T22:20:13.61247Z","end":"2026-06-02T22:20:13.864961Z","steps":["trace[1241143240] 'process raft request' (duration: 199.431441ms)","trace[1241143240] 'compare' (duration: 52.695955ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:13.865487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.17683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277945"} {"level":"info","ts":"2026-06-02T22:20:13.865562Z","caller":"traceutil/trace.go:171","msg":"trace[579681387] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25348; }","duration":"153.312384ms","start":"2026-06-02T22:20:13.712232Z","end":"2026-06-02T22:20:13.865545Z","steps":["trace[579681387] 'agreement among raft nodes before linearized reading' (duration: 152.7523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.865828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.164795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-06-02T22:20:13.865905Z","caller":"traceutil/trace.go:171","msg":"trace[1510706836] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25348; }","duration":"209.302738ms","start":"2026-06-02T22:20:13.656581Z","end":"2026-06-02T22:20:13.865884Z","steps":["trace[1510706836] 'agreement among raft nodes before linearized reading' (duration: 209.074302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.490373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.771831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:21.490481Z","caller":"traceutil/trace.go:171","msg":"trace[1064156940] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25466; }","duration":"150.934615ms","start":"2026-06-02T22:20:21.339518Z","end":"2026-06-02T22:20:21.490453Z","steps":["trace[1064156940] 'count revisions from in-memory index tree' (duration: 150.663209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.491096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.68252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e35b7639-cf49-461d-95e4-adcf2722afc1\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-02T22:20:21.491137Z","caller":"traceutil/trace.go:171","msg":"trace[994559214] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e35b7639-cf49-461d-95e4-adcf2722afc1; range_end:; response_count:1; response_revision:25466; }","duration":"262.765022ms","start":"2026-06-02T22:20:21.22836Z","end":"2026-06-02T22:20:21.491125Z","steps":["trace[994559214] 'range keys from in-memory index tree' (duration: 262.460755ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:21.975811Z","caller":"traceutil/trace.go:171","msg":"trace[104045013] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"332.809774ms","start":"2026-06-02T22:20:21.642978Z","end":"2026-06-02T22:20:21.975788Z","steps":["trace[104045013] 'process raft request' (duration: 332.741853ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:21.975879Z","caller":"traceutil/trace.go:171","msg":"trace[699345255] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"336.306648ms","start":"2026-06-02T22:20:21.639565Z","end":"2026-06-02T22:20:21.975872Z","steps":["trace[699345255] 'process raft request' (duration: 336.122464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.975979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:21.642942Z","time spent":"332.942788ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:21.976011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:21.639535Z","time spent":"336.38128ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:21.975809Z","caller":"traceutil/trace.go:171","msg":"trace[1068338886] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"336.483123ms","start":"2026-06-02T22:20:21.639289Z","end":"2026-06-02T22:20:21.975772Z","steps":["trace[1068338886] 'process raft request' (duration: 336.189186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.976137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:21.639253Z","time spent":"336.867821ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:21.976183Z","caller":"traceutil/trace.go:171","msg":"trace[2056949216] linearizableReadLoop","detail":"{readStateIndex:26300; appliedIndex:26296; }","duration":"331.889163ms","start":"2026-06-02T22:20:21.644285Z","end":"2026-06-02T22:20:21.976174Z","steps":["trace[2056949216] 'read index received' (duration: 42.609244ms)","trace[2056949216] 'applied index is now lower than readState.Index' (duration: 289.278489ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:21.976279Z","caller":"traceutil/trace.go:171","msg":"trace[2062930936] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"331.701758ms","start":"2026-06-02T22:20:21.644522Z","end":"2026-06-02T22:20:21.976224Z","steps":["trace[2062930936] 'process raft request' (duration: 331.222976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.976577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.95502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-02T22:20:21.976608Z","caller":"traceutil/trace.go:171","msg":"trace[1048922563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25472; }","duration":"331.020022ms","start":"2026-06-02T22:20:21.64558Z","end":"2026-06-02T22:20:21.9766Z","steps":["trace[1048922563] 'agreement among raft nodes before linearized reading' (duration: 330.903319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.976629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:21.645573Z","time spent":"331.052312ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T22:20:21.97666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.379995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T22:20:21.976697Z","caller":"traceutil/trace.go:171","msg":"trace[1156442993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25472; }","duration":"332.454456ms","start":"2026-06-02T22:20:21.644235Z","end":"2026-06-02T22:20:21.976689Z","steps":["trace[1156442993] 'agreement among raft nodes before linearized reading' (duration: 332.006065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.97672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:21.644223Z","time spent":"332.492347ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T22:20:21.97685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.201963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-02T22:20:21.976874Z","caller":"traceutil/trace.go:171","msg":"trace[153141637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25472; }","duration":"328.236314ms","start":"2026-06-02T22:20:21.64863Z","end":"2026-06-02T22:20:21.976867Z","steps":["trace[153141637] 'agreement among raft nodes before linearized reading' (duration: 328.159522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.976893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:21.648623Z","time spent":"328.265105ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T22:20:21.976972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.013158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:20:21.977077Z","caller":"traceutil/trace.go:171","msg":"trace[757828568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25472; }","duration":"330.164001ms","start":"2026-06-02T22:20:21.646885Z","end":"2026-06-02T22:20:21.977049Z","steps":["trace[757828568] 'agreement among raft nodes before linearized reading' (duration: 329.901345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.977133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:21.646872Z","time spent":"330.251483ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T22:20:21.978018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:21.644512Z","time spent":"331.978954ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:22.128456Z","caller":"traceutil/trace.go:171","msg":"trace[701741784] linearizableReadLoop","detail":"{readStateIndex:26305; appliedIndex:26301; }","duration":"145.977776ms","start":"2026-06-02T22:20:21.98245Z","end":"2026-06-02T22:20:22.128427Z","steps":["trace[701741784] 'read index received' (duration: 93.975328ms)","trace[701741784] 'applied index is now lower than readState.Index' (duration: 52.001108ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:22.128772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.295534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-02T22:20:22.12884Z","caller":"traceutil/trace.go:171","msg":"trace[1133694747] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:25477; }","duration":"146.441138ms","start":"2026-06-02T22:20:21.982385Z","end":"2026-06-02T22:20:22.128826Z","steps":["trace[1133694747] 'agreement among raft nodes before linearized reading' (duration: 146.166701ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:22.128984Z","caller":"traceutil/trace.go:171","msg":"trace[932442386] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"146.909349ms","start":"2026-06-02T22:20:21.982067Z","end":"2026-06-02T22:20:22.128977Z","steps":["trace[932442386] 'process raft request' (duration: 146.037918ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:22.12915Z","caller":"traceutil/trace.go:171","msg":"trace[1645640464] transaction","detail":"{read_only:false; response_revision:25476; number_of_response:1; }","duration":"146.865568ms","start":"2026-06-02T22:20:21.982267Z","end":"2026-06-02T22:20:22.129133Z","steps":["trace[1645640464] 'process raft request' (duration: 146.073028ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:22.129289Z","caller":"traceutil/trace.go:171","msg":"trace[1009950924] transaction","detail":"{read_only:false; response_revision:25477; number_of_response:1; }","duration":"145.906085ms","start":"2026-06-02T22:20:21.98337Z","end":"2026-06-02T22:20:22.129276Z","steps":["trace[1009950924] 'process raft request' (duration: 145.016543ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:22.128785Z","caller":"traceutil/trace.go:171","msg":"trace[2020421479] transaction","detail":"{read_only:false; response_revision:25475; number_of_response:1; }","duration":"146.539209ms","start":"2026-06-02T22:20:21.982222Z","end":"2026-06-02T22:20:22.128762Z","steps":["trace[2020421479] 'process raft request' (duration: 146.070458ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:22.475537Z","caller":"traceutil/trace.go:171","msg":"trace[1570485097] linearizableReadLoop","detail":"{readStateIndex:26310; appliedIndex:26309; }","duration":"213.920978ms","start":"2026-06-02T22:20:22.261588Z","end":"2026-06-02T22:20:22.475509Z","steps":["trace[1570485097] 'read index received' (duration: 213.56303ms)","trace[1570485097] 'applied index is now lower than readState.Index' (duration: 356.788µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:22.475683Z","caller":"traceutil/trace.go:171","msg":"trace[813825220] transaction","detail":"{read_only:false; response_revision:25480; number_of_response:1; }","duration":"225.356903ms","start":"2026-06-02T22:20:22.250283Z","end":"2026-06-02T22:20:22.47564Z","steps":["trace[813825220] 'process raft request' (duration: 225.028865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:22.476029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.737477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-06-02T22:20:22.476127Z","caller":"traceutil/trace.go:171","msg":"trace[351985183] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25480; }","duration":"209.877371ms","start":"2026-06-02T22:20:22.266225Z","end":"2026-06-02T22:20:22.476102Z","steps":["trace[351985183] 'agreement among raft nodes before linearized reading' (duration: 209.489922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:22.476132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.507743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-06-02T22:20:22.476226Z","caller":"traceutil/trace.go:171","msg":"trace[900593078] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25480; }","duration":"214.666626ms","start":"2026-06-02T22:20:22.261538Z","end":"2026-06-02T22:20:22.476204Z","steps":["trace[900593078] 'agreement among raft nodes before linearized reading' (duration: 214.127204ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:22.646475Z","caller":"traceutil/trace.go:171","msg":"trace[260156285] linearizableReadLoop","detail":"{readStateIndex:26312; appliedIndex:26311; }","duration":"118.083236ms","start":"2026-06-02T22:20:22.52836Z","end":"2026-06-02T22:20:22.646444Z","steps":["trace[260156285] 'read index received' (duration: 50.509943ms)","trace[260156285] 'applied index is now lower than readState.Index' (duration: 67.572283ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:22.646545Z","caller":"traceutil/trace.go:171","msg":"trace[29005501] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"125.440343ms","start":"2026-06-02T22:20:22.521048Z","end":"2026-06-02T22:20:22.646489Z","steps":["trace[29005501] 'process raft request' (duration: 57.934962ms)","trace[29005501] 'compare' (duration: 67.221744ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:22.646867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.486436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-06-02T22:20:22.646954Z","caller":"traceutil/trace.go:171","msg":"trace[2070207597] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25482; }","duration":"118.618389ms","start":"2026-06-02T22:20:22.528323Z","end":"2026-06-02T22:20:22.646942Z","steps":["trace[2070207597] 'agreement among raft nodes before linearized reading' (duration: 118.209109ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:32.302551Z","caller":"traceutil/trace.go:171","msg":"trace[928340976] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"284.473733ms","start":"2026-06-02T22:20:32.018038Z","end":"2026-06-02T22:20:32.302512Z","steps":["trace[928340976] 'process raft request' (duration: 284.194186ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:32.302837Z","caller":"traceutil/trace.go:171","msg":"trace[460188863] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"284.690678ms","start":"2026-06-02T22:20:32.018091Z","end":"2026-06-02T22:20:32.302781Z","steps":["trace[460188863] 'process raft request' (duration: 284.35286ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:32.302909Z","caller":"traceutil/trace.go:171","msg":"trace[991570277] linearizableReadLoop","detail":"{readStateIndex:26411; appliedIndex:26408; }","duration":"179.628814ms","start":"2026-06-02T22:20:32.12325Z","end":"2026-06-02T22:20:32.302879Z","steps":["trace[991570277] 'read index received' (duration: 178.895677ms)","trace[991570277] 'applied index is now lower than readState.Index' (duration: 731.677µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:32.303101Z","caller":"traceutil/trace.go:171","msg":"trace[1882787468] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"284.926864ms","start":"2026-06-02T22:20:32.01814Z","end":"2026-06-02T22:20:32.303067Z","steps":["trace[1882787468] 'process raft request' (duration: 284.583236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:32.303292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.011183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-02T22:20:32.303341Z","caller":"traceutil/trace.go:171","msg":"trace[1800627532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25578; }","duration":"180.111776ms","start":"2026-06-02T22:20:32.123214Z","end":"2026-06-02T22:20:32.303326Z","steps":["trace[1800627532] 'agreement among raft nodes before linearized reading' (duration: 179.902501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:32.316476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.95893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-02T22:20:32.316554Z","caller":"traceutil/trace.go:171","msg":"trace[1878845484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25579; }","duration":"137.081572ms","start":"2026-06-02T22:20:32.179452Z","end":"2026-06-02T22:20:32.316533Z","steps":["trace[1878845484] 'agreement among raft nodes before linearized reading' (duration: 136.773765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:32.316727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.103357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-02T22:20:32.316802Z","caller":"traceutil/trace.go:171","msg":"trace[2027230188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25579; }","duration":"133.24942ms","start":"2026-06-02T22:20:32.183531Z","end":"2026-06-02T22:20:32.31678Z","steps":["trace[2027230188] 'agreement among raft nodes before linearized reading' (duration: 133.029545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:32.31686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.792844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-02T22:20:32.316913Z","caller":"traceutil/trace.go:171","msg":"trace[1383391161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25579; }","duration":"133.897127ms","start":"2026-06-02T22:20:32.183004Z","end":"2026-06-02T22:20:32.316901Z","steps":["trace[1383391161] 'agreement among raft nodes before linearized reading' (duration: 133.754704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:32.316977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.680669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T22:20:32.317019Z","caller":"traceutil/trace.go:171","msg":"trace[6462625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25579; }","duration":"130.75705ms","start":"2026-06-02T22:20:32.186251Z","end":"2026-06-02T22:20:32.317008Z","steps":["trace[6462625] 'agreement among raft nodes before linearized reading' (duration: 130.653537ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:32.451046Z","caller":"traceutil/trace.go:171","msg":"trace[1813289170] transaction","detail":"{read_only:false; response_revision:25581; number_of_response:1; }","duration":"129.372058ms","start":"2026-06-02T22:20:32.321632Z","end":"2026-06-02T22:20:32.451004Z","steps":["trace[1813289170] 'process raft request' (duration: 102.217575ms)","trace[1813289170] 'compare' (duration: 26.928507ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:32.45143Z","caller":"traceutil/trace.go:171","msg":"trace[675798261] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"129.383688ms","start":"2026-06-02T22:20:32.321987Z","end":"2026-06-02T22:20:32.45137Z","steps":["trace[675798261] 'process raft request' (duration: 128.970459ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:32.4516Z","caller":"traceutil/trace.go:171","msg":"trace[1791780283] transaction","detail":"{read_only:false; response_revision:25584; number_of_response:1; }","duration":"128.947697ms","start":"2026-06-02T22:20:32.322637Z","end":"2026-06-02T22:20:32.451585Z","steps":["trace[1791780283] 'process raft request' (duration: 128.727802ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:32.451654Z","caller":"traceutil/trace.go:171","msg":"trace[29216399] transaction","detail":"{read_only:false; response_revision:25583; number_of_response:1; }","duration":"129.544302ms","start":"2026-06-02T22:20:32.322034Z","end":"2026-06-02T22:20:32.451578Z","steps":["trace[29216399] 'process raft request' (duration: 129.232135ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:34.416976Z","caller":"traceutil/trace.go:171","msg":"trace[1421417618] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"107.39154ms","start":"2026-06-02T22:20:34.309554Z","end":"2026-06-02T22:20:34.416945Z","steps":["trace[1421417618] 'process raft request' (duration: 106.98907ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:34.417118Z","caller":"traceutil/trace.go:171","msg":"trace[1209090419] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"106.928489ms","start":"2026-06-02T22:20:34.31014Z","end":"2026-06-02T22:20:34.417068Z","steps":["trace[1209090419] 'process raft request' (duration: 106.615011ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:36.922656Z","caller":"traceutil/trace.go:171","msg":"trace[115582846] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"105.853032ms","start":"2026-06-02T22:20:36.816767Z","end":"2026-06-02T22:20:36.92262Z","steps":["trace[115582846] 'process raft request' (duration: 105.640547ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:39.119735Z","caller":"traceutil/trace.go:171","msg":"trace[367293887] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"134.878051ms","start":"2026-06-02T22:20:38.984812Z","end":"2026-06-02T22:20:39.11969Z","steps":["trace[367293887] 'process raft request' (duration: 132.736369ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:40.505069Z","caller":"traceutil/trace.go:171","msg":"trace[525511113] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"153.959458ms","start":"2026-06-02T22:20:40.351078Z","end":"2026-06-02T22:20:40.505038Z","steps":["trace[525511113] 'process raft request' (duration: 153.785084ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:52.656925Z","caller":"traceutil/trace.go:171","msg":"trace[942967462] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"107.922052ms","start":"2026-06-02T22:20:52.548976Z","end":"2026-06-02T22:20:52.656898Z","steps":["trace[942967462] 'process raft request' (duration: 107.764699ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:52.708011Z","caller":"traceutil/trace.go:171","msg":"trace[241865397] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"139.335557ms","start":"2026-06-02T22:20:52.568649Z","end":"2026-06-02T22:20:52.707984Z","steps":["trace[241865397] 'process raft request' (duration: 139.266526ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:52.708144Z","caller":"traceutil/trace.go:171","msg":"trace[19448485] linearizableReadLoop","detail":"{readStateIndex:26574; appliedIndex:26570; }","duration":"121.545959ms","start":"2026-06-02T22:20:52.58657Z","end":"2026-06-02T22:20:52.708116Z","steps":["trace[19448485] 'read index received' (duration: 70.210737ms)","trace[19448485] 'applied index is now lower than readState.Index' (duration: 51.333822ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:52.708298Z","caller":"traceutil/trace.go:171","msg":"trace[1867501742] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"138.771983ms","start":"2026-06-02T22:20:52.569517Z","end":"2026-06-02T22:20:52.708289Z","steps":["trace[1867501742] 'process raft request' (duration: 138.437535ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:52.708392Z","caller":"traceutil/trace.go:171","msg":"trace[810197119] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"139.801899ms","start":"2026-06-02T22:20:52.568579Z","end":"2026-06-02T22:20:52.708381Z","steps":["trace[810197119] 'process raft request' (duration: 125.021094ms)","trace[810197119] 'compare' (duration: 14.17389ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:52.708548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.957099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-02T22:20:52.708586Z","caller":"traceutil/trace.go:171","msg":"trace[227056469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25737; }","duration":"122.01037ms","start":"2026-06-02T22:20:52.586562Z","end":"2026-06-02T22:20:52.708572Z","steps":["trace[227056469] 'agreement among raft nodes before linearized reading' (duration: 121.875087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:52.708695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.046921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-02T22:20:52.708719Z","caller":"traceutil/trace.go:171","msg":"trace[1811647440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25737; }","duration":"122.127913ms","start":"2026-06-02T22:20:52.586585Z","end":"2026-06-02T22:20:52.708713Z","steps":["trace[1811647440] 'agreement among raft nodes before linearized reading' (duration: 122.065871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:52.708978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.039124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-02T22:20:52.709004Z","caller":"traceutil/trace.go:171","msg":"trace[1249593247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25737; }","duration":"110.100885ms","start":"2026-06-02T22:20:52.598897Z","end":"2026-06-02T22:20:52.708998Z","steps":["trace[1249593247] 'agreement among raft nodes before linearized reading' (duration: 110.038524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:52.709065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.38584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T22:20:52.709107Z","caller":"traceutil/trace.go:171","msg":"trace[1011798485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25737; }","duration":"122.486953ms","start":"2026-06-02T22:20:52.586611Z","end":"2026-06-02T22:20:52.709098Z","steps":["trace[1011798485] 'agreement among raft nodes before linearized reading' (duration: 122.35409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:52.709266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.004058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:20:52.709294Z","caller":"traceutil/trace.go:171","msg":"trace[1732443577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25737; }","duration":"116.077359ms","start":"2026-06-02T22:20:52.59321Z","end":"2026-06-02T22:20:52.709287Z","steps":["trace[1732443577] 'agreement among raft nodes before linearized reading' (duration: 116.005398ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:52.913568Z","caller":"traceutil/trace.go:171","msg":"trace[373336953] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"199.156655ms","start":"2026-06-02T22:20:52.714372Z","end":"2026-06-02T22:20:52.913528Z","steps":["trace[373336953] 'process raft request' (duration: 197.526826ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:53.157611Z","caller":"traceutil/trace.go:171","msg":"trace[202387823] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"443.067772ms","start":"2026-06-02T22:20:52.714508Z","end":"2026-06-02T22:20:53.157576Z","steps":["trace[202387823] 'process raft request' (duration: 442.94708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:53.157852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:52.714501Z","time spent":"443.229627ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:53.158125Z","caller":"traceutil/trace.go:171","msg":"trace[138315965] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"443.673187ms","start":"2026-06-02T22:20:52.714395Z","end":"2026-06-02T22:20:53.158068Z","steps":["trace[138315965] 'process raft request' (duration: 442.885558ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:53.158242Z","caller":"traceutil/trace.go:171","msg":"trace[1982103339] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"443.626816ms","start":"2026-06-02T22:20:52.714578Z","end":"2026-06-02T22:20:53.158205Z","steps":["trace[1982103339] 'process raft request' (duration: 442.93833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:53.15843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:52.714573Z","time spent":"443.744119ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:53.158508Z","caller":"traceutil/trace.go:171","msg":"trace[2121630199] linearizableReadLoop","detail":"{readStateIndex:26578; appliedIndex:26574; }","duration":"443.832181ms","start":"2026-06-02T22:20:52.714647Z","end":"2026-06-02T22:20:53.15848Z","steps":["trace[2121630199] 'read index received' (duration: 197.191916ms)","trace[2121630199] 'applied index is now lower than readState.Index' (duration: 246.639305ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:53.158634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:52.714371Z","time spent":"444.000456ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:53.158773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.109077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-06-02T22:20:53.1588Z","caller":"traceutil/trace.go:171","msg":"trace[734261308] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25742; }","duration":"444.162638ms","start":"2026-06-02T22:20:52.714632Z","end":"2026-06-02T22:20:53.158794Z","steps":["trace[734261308] 'agreement among raft nodes before linearized reading' (duration: 444.041235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:53.15882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:52.714589Z","time spent":"444.22579ms","remote":"127.0.0.1:40372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1335,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-06-02T22:20:53.15866Z","caller":"traceutil/trace.go:171","msg":"trace[1554807345] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"444.006395ms","start":"2026-06-02T22:20:52.714645Z","end":"2026-06-02T22:20:53.158651Z","steps":["trace[1554807345] 'process raft request' (duration: 443.39109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:53.158951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:52.714638Z","time spent":"444.288601ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:53.215371Z","caller":"traceutil/trace.go:171","msg":"trace[1885183547] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"207.957965ms","start":"2026-06-02T22:20:53.007326Z","end":"2026-06-02T22:20:53.215284Z","steps":["trace[1885183547] 'process raft request' (duration: 207.800931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:53.215507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.997209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:53.216212Z","caller":"traceutil/trace.go:171","msg":"trace[1118101001] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25743; }","duration":"470.739627ms","start":"2026-06-02T22:20:52.745445Z","end":"2026-06-02T22:20:53.216185Z","steps":["trace[1118101001] 'agreement among raft nodes before linearized reading' (duration: 470.019559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:53.21643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:52.745419Z","time spent":"470.933432ms","remote":"127.0.0.1:47778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"info","ts":"2026-06-02T22:20:53.362117Z","caller":"traceutil/trace.go:171","msg":"trace[1025413948] linearizableReadLoop","detail":"{readStateIndex:26581; appliedIndex:26580; }","duration":"146.849398ms","start":"2026-06-02T22:20:53.21524Z","end":"2026-06-02T22:20:53.36209Z","steps":["trace[1025413948] 'read index received' (duration: 72.686066ms)","trace[1025413948] 'applied index is now lower than readState.Index' (duration: 74.162482ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:53.362172Z","caller":"traceutil/trace.go:171","msg":"trace[1766059514] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"157.39941ms","start":"2026-06-02T22:20:53.204713Z","end":"2026-06-02T22:20:53.362112Z","steps":["trace[1766059514] 'process raft request' (duration: 83.052404ms)","trace[1766059514] 'compare' (duration: 74.159872ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:53.362311Z","caller":"traceutil/trace.go:171","msg":"trace[1722118980] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"134.520811ms","start":"2026-06-02T22:20:53.227756Z","end":"2026-06-02T22:20:53.362277Z","steps":["trace[1722118980] 'process raft request' (duration: 134.302586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:53.362369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.576962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:53.362411Z","caller":"traceutil/trace.go:171","msg":"trace[1726034376] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25745; }","duration":"196.663464ms","start":"2026-06-02T22:20:53.165731Z","end":"2026-06-02T22:20:53.362394Z","steps":["trace[1726034376] 'agreement among raft nodes before linearized reading' (duration: 196.50834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:53.362715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.619824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:20:53.362771Z","caller":"traceutil/trace.go:171","msg":"trace[325922748] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25745; }","duration":"124.786798ms","start":"2026-06-02T22:20:53.237972Z","end":"2026-06-02T22:20:53.362759Z","steps":["trace[325922748] 'agreement among raft nodes before linearized reading' (duration: 124.644774ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:56.786671Z","caller":"traceutil/trace.go:171","msg":"trace[882303657] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"117.35283ms","start":"2026-06-02T22:20:56.669285Z","end":"2026-06-02T22:20:56.786638Z","steps":["trace[882303657] 'process raft request' (duration: 117.165275ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:56.909127Z","caller":"traceutil/trace.go:171","msg":"trace[1063067529] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"190.289962ms","start":"2026-06-02T22:20:56.718809Z","end":"2026-06-02T22:20:56.909099Z","steps":["trace[1063067529] 'process raft request' (duration: 180.318532ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:56.909557Z","caller":"traceutil/trace.go:171","msg":"trace[646360576] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"186.542332ms","start":"2026-06-02T22:20:56.722987Z","end":"2026-06-02T22:20:56.909529Z","steps":["trace[646360576] 'process raft request' (duration: 186.04783ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:56.909647Z","caller":"traceutil/trace.go:171","msg":"trace[1932448350] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"186.431738ms","start":"2026-06-02T22:20:56.723177Z","end":"2026-06-02T22:20:56.909609Z","steps":["trace[1932448350] 'process raft request' (duration: 186.233733ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:08.505036Z","caller":"traceutil/trace.go:171","msg":"trace[737416465] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"263.055279ms","start":"2026-06-02T22:21:08.241948Z","end":"2026-06-02T22:21:08.505003Z","steps":["trace[737416465] 'process raft request' (duration: 262.825154ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:08.697486Z","caller":"traceutil/trace.go:171","msg":"trace[1823892628] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"451.136237ms","start":"2026-06-02T22:21:08.246328Z","end":"2026-06-02T22:21:08.697465Z","steps":["trace[1823892628] 'process raft request' (duration: 450.576194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:21:08.69763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:21:08.246285Z","time spent":"451.26524ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:21:08.697686Z","caller":"traceutil/trace.go:171","msg":"trace[1864779143] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"312.981758ms","start":"2026-06-02T22:21:08.384664Z","end":"2026-06-02T22:21:08.697646Z","steps":["trace[1864779143] 'process raft request' (duration: 312.751653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:21:08.697875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:21:08.384638Z","time spent":"313.127232ms","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:21:08.787198Z","caller":"traceutil/trace.go:171","msg":"trace[758735808] linearizableReadLoop","detail":"{readStateIndex:26696; appliedIndex:26693; }","duration":"103.940036ms","start":"2026-06-02T22:21:08.683227Z","end":"2026-06-02T22:21:08.787167Z","steps":["trace[758735808] 'read index received' (duration: 14.392446ms)","trace[758735808] 'applied index is now lower than readState.Index' (duration: 89.54613ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:21:08.787455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.208572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-06-02T22:21:08.787506Z","caller":"traceutil/trace.go:171","msg":"trace[1948836265] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25855; }","duration":"104.322115ms","start":"2026-06-02T22:21:08.683169Z","end":"2026-06-02T22:21:08.787491Z","steps":["trace[1948836265] 'agreement among raft nodes before linearized reading' (duration: 104.11768ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:08.787641Z","caller":"traceutil/trace.go:171","msg":"trace[1315294692] transaction","detail":"{read_only:false; response_revision:25854; number_of_response:1; }","duration":"275.745454ms","start":"2026-06-02T22:21:08.511858Z","end":"2026-06-02T22:21:08.787603Z","steps":["trace[1315294692] 'process raft request' (duration: 275.047927ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:08.787772Z","caller":"traceutil/trace.go:171","msg":"trace[1560322634] transaction","detail":"{read_only:false; response_revision:25855; number_of_response:1; }","duration":"275.1913ms","start":"2026-06-02T22:21:08.512567Z","end":"2026-06-02T22:21:08.787759Z","steps":["trace[1560322634] 'process raft request' (duration: 274.515564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:21:08.923899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.138142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:21:08.924042Z","caller":"traceutil/trace.go:171","msg":"trace[352144802] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:25855; }","duration":"119.334407ms","start":"2026-06-02T22:21:08.804659Z","end":"2026-06-02T22:21:08.923993Z","steps":["trace[352144802] 'count revisions from in-memory index tree' (duration: 119.05268ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:08.939963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22417} {"level":"info","ts":"2026-06-02T22:21:09.054315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22417,"took":"109.210564ms","hash":1646018837,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":43966464,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-06-02T22:21:09.054394Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1646018837,"revision":22417,"compact-revision":20234} {"level":"info","ts":"2026-06-02T22:21:12.818619Z","caller":"traceutil/trace.go:171","msg":"trace[1156833659] linearizableReadLoop","detail":"{readStateIndex:26733; appliedIndex:26732; }","duration":"100.661548ms","start":"2026-06-02T22:21:12.717933Z","end":"2026-06-02T22:21:12.818594Z","steps":["trace[1156833659] 'read index received' (duration: 100.466574ms)","trace[1156833659] 'applied index is now lower than readState.Index' (duration: 193.964µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:21:12.818797Z","caller":"traceutil/trace.go:171","msg":"trace[576590713] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"108.173729ms","start":"2026-06-02T22:21:12.710465Z","end":"2026-06-02T22:21:12.818639Z","steps":["trace[576590713] 'process raft request' (duration: 107.947814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:21:12.818874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.906004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-06-02T22:21:12.818987Z","caller":"traceutil/trace.go:171","msg":"trace[920458649] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25890; }","duration":"101.091118ms","start":"2026-06-02T22:21:12.717871Z","end":"2026-06-02T22:21:12.818962Z","steps":["trace[920458649] 'agreement among raft nodes before linearized reading' (duration: 100.826002ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:18.21368Z","caller":"traceutil/trace.go:171","msg":"trace[362336741] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"115.216578ms","start":"2026-06-02T22:21:18.098429Z","end":"2026-06-02T22:21:18.213645Z","steps":["trace[362336741] 'process raft request' (duration: 114.647094ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:19.012679Z","caller":"traceutil/trace.go:171","msg":"trace[1532565153] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"172.291388ms","start":"2026-06-02T22:21:18.840344Z","end":"2026-06-02T22:21:19.012636Z","steps":["trace[1532565153] 'process raft request' (duration: 171.996691ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:19.537993Z","caller":"traceutil/trace.go:171","msg":"trace[169494768] transaction","detail":"{read_only:false; response_revision:25941; number_of_response:1; }","duration":"196.532783ms","start":"2026-06-02T22:21:19.341423Z","end":"2026-06-02T22:21:19.537955Z","steps":["trace[169494768] 'process raft request' (duration: 196.332458ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:19.538512Z","caller":"traceutil/trace.go:171","msg":"trace[804430881] linearizableReadLoop","detail":"{readStateIndex:26785; appliedIndex:26785; }","duration":"188.920699ms","start":"2026-06-02T22:21:19.349566Z","end":"2026-06-02T22:21:19.538487Z","steps":["trace[804430881] 'read index received' (duration: 188.910549ms)","trace[804430881] 'applied index is now lower than readState.Index' (duration: 8.31µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:21:19.538761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.171025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-02T22:21:19.538794Z","caller":"traceutil/trace.go:171","msg":"trace[671496941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25941; }","duration":"189.252807ms","start":"2026-06-02T22:21:19.349533Z","end":"2026-06-02T22:21:19.538786Z","steps":["trace[671496941] 'agreement among raft nodes before linearized reading' (duration: 189.075843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:21:19.546207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.335622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-02T22:21:19.546281Z","caller":"traceutil/trace.go:171","msg":"trace[87338727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25943; }","duration":"155.515146ms","start":"2026-06-02T22:21:19.390749Z","end":"2026-06-02T22:21:19.546264Z","steps":["trace[87338727] 'agreement among raft nodes before linearized reading' (duration: 155.26191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:21:19.546452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.50614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T22:21:19.546551Z","caller":"traceutil/trace.go:171","msg":"trace[926192973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25943; }","duration":"151.676703ms","start":"2026-06-02T22:21:19.394854Z","end":"2026-06-02T22:21:19.546531Z","steps":["trace[926192973] 'agreement among raft nodes before linearized reading' (duration: 151.390727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:21:19.546797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.456282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-02T22:21:19.546864Z","caller":"traceutil/trace.go:171","msg":"trace[1619305418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25943; }","duration":"147.546485ms","start":"2026-06-02T22:21:19.399301Z","end":"2026-06-02T22:21:19.546848Z","steps":["trace[1619305418] 'agreement among raft nodes before linearized reading' (duration: 146.869409ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:38.535937Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069a3.wal"} {"level":"info","ts":"2026-06-02T22:26:08.946897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25857} {"level":"info","ts":"2026-06-02T22:26:09.104261Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25857,"took":"151.481838ms","hash":1956021638,"current-db-size-bytes":60755968,"current-db-size":"61 MB","current-db-size-in-use-bytes":36339712,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-02T22:26:09.104341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1956021638,"revision":25857,"compact-revision":22417} {"level":"info","ts":"2026-06-02T22:26:55.499961Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T22:26:55.505301Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-02T22:26:55.505536Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-02T22:31:08.95393Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28737} {"level":"info","ts":"2026-06-02T22:31:09.109921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28737,"took":"150.611908ms","hash":3169945045,"current-db-size-bytes":60755968,"current-db-size":"61 MB","current-db-size-in-use-bytes":30814208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T22:31:09.109994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3169945045,"revision":28737,"compact-revision":25857} {"level":"info","ts":"2026-06-02T22:36:08.968074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30960} {"level":"info","ts":"2026-06-02T22:36:09.113908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30960,"took":"140.566713ms","hash":2266577371,"current-db-size-bytes":60755968,"current-db-size":"61 MB","current-db-size-in-use-bytes":30289920,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T22:36:09.113995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2266577371,"revision":30960,"compact-revision":28737} {"level":"info","ts":"2026-06-02T22:41:08.975887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33219} {"level":"info","ts":"2026-06-02T22:41:09.122659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33219,"took":"141.825884ms","hash":545872938,"current-db-size-bytes":61796352,"current-db-size":"62 MB","current-db-size-in-use-bytes":30908416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T22:41:09.122726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":545872938,"revision":33219,"compact-revision":30960} {"level":"info","ts":"2026-06-02T22:46:08.982775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35515} {"level":"info","ts":"2026-06-02T22:46:09.128035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35515,"took":"139.000324ms","hash":3059561541,"current-db-size-bytes":62218240,"current-db-size":"62 MB","current-db-size-in-use-bytes":31547392,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T22:46:09.128115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3059561541,"revision":35515,"compact-revision":33219} {"level":"info","ts":"2026-06-02T22:48:26.769189Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T22:48:26.773962Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-02T22:48:26.774067Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-02T22:51:08.989311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37741} {"level":"info","ts":"2026-06-02T22:51:09.113588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37741,"took":"118.872923ms","hash":3608950016,"current-db-size-bytes":63483904,"current-db-size":"64 MB","current-db-size-in-use-bytes":31424512,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T22:51:09.113685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3608950016,"revision":37741,"compact-revision":35515}