{"level":"warn","ts":"2026-06-24T17:54:22.905881Z","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-24T17:54:22.90596Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.130:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.130:2380","--initial-cluster=instance=https://199.19.213.130:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.130:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.130: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-24T17:54:22.906017Z","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-24T17:54:22.906029Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-06-24T17:54:22.906044Z","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-24T17:54:22.906716Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"]} {"level":"info","ts":"2026-06-24T17:54:22.906793Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.130: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-24T17:54:22.910354Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.382312ms"} {"level":"info","ts":"2026-06-24T17:54:22.91648Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a727d7e7ce5fe36a","cluster-id":"db8a5e7d54164af4"} {"level":"info","ts":"2026-06-24T17:54:22.916597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=()"} {"level":"info","ts":"2026-06-24T17:54:22.916629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 0"} {"level":"info","ts":"2026-06-24T17:54:22.916651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a727d7e7ce5fe36a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-24T17:54:22.916669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 1"} {"level":"info","ts":"2026-06-24T17:54:22.916721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"warn","ts":"2026-06-24T17:54:22.925614Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-24T17:54:22.927514Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-24T17:54:22.933594Z","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-24T17:54:22.936509Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a727d7e7ce5fe36a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-24T17:54:22.938533Z","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-24T17:54:22.938647Z","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-24T17:54:22.93866Z","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-24T17:54:22.937714Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a727d7e7ce5fe36a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-24T17:54:22.944366Z","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-24T17:54:22.944461Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-06-24T17:54:22.944516Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-06-24T17:54:22.946741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"info","ts":"2026-06-24T17:54:22.946742Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a727d7e7ce5fe36a","initial-advertise-peer-urls":["https://199.19.213.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-24T17:54:22.946794Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-24T17:54:22.946834Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","added-peer-id":"a727d7e7ce5fe36a","added-peer-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-06-24T17:54:23.017124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a is starting a new election at term 1"} {"level":"info","ts":"2026-06-24T17:54:23.017182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became pre-candidate at term 1"} {"level":"info","ts":"2026-06-24T17:54:23.017215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgPreVoteResp from a727d7e7ce5fe36a at term 1"} {"level":"info","ts":"2026-06-24T17:54:23.017234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became candidate at term 2"} {"level":"info","ts":"2026-06-24T17:54:23.017245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgVoteResp from a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-06-24T17:54:23.017268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became leader at term 2"} {"level":"info","ts":"2026-06-24T17:54:23.017288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a727d7e7ce5fe36a elected leader a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-06-24T17:54:23.018282Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:54:23.019165Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T17:54:23.019167Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a727d7e7ce5fe36a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.130:2379]}","request-path":"/0/members/a727d7e7ce5fe36a/attributes","cluster-id":"db8a5e7d54164af4","publish-timeout":"7s"} {"level":"info","ts":"2026-06-24T17:54:23.019315Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T17:54:23.019567Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-24T17:54:23.019591Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-24T17:54:23.019665Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:54:23.01977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:54:23.019817Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:54:23.02151Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.130:2379"} {"level":"info","ts":"2026-06-24T17:54:23.022525Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-24T17:54:29.226858Z","caller":"traceutil/trace.go:171","msg":"trace[1180883667] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"119.627988ms","start":"2026-06-24T17:54:29.107209Z","end":"2026-06-24T17:54:29.226837Z","steps":["trace[1180883667] 'process raft request' (duration: 119.542256ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:54:29.281227Z","caller":"traceutil/trace.go:171","msg":"trace[422562610] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"173.299643ms","start":"2026-06-24T17:54:29.107546Z","end":"2026-06-24T17:54:29.280845Z","steps":["trace[422562610] 'process raft request' (duration: 173.114248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:54:29.282101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.33449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-24T17:54:29.282222Z","caller":"traceutil/trace.go:171","msg":"trace[1619236611] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:305; }","duration":"168.558786ms","start":"2026-06-24T17:54:29.113646Z","end":"2026-06-24T17:54:29.282205Z","steps":["trace[1619236611] 'agreement among raft nodes before linearized reading' (duration: 167.674882ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:54:29.282765Z","caller":"traceutil/trace.go:171","msg":"trace[769645484] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"173.280712ms","start":"2026-06-24T17:54:29.109458Z","end":"2026-06-24T17:54:29.282738Z","steps":["trace[769645484] 'process raft request' (duration: 171.687049ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:54:29.283003Z","caller":"traceutil/trace.go:171","msg":"trace[954344166] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:313; }","duration":"167.57465ms","start":"2026-06-24T17:54:29.113703Z","end":"2026-06-24T17:54:29.281278Z","steps":["trace[954344166] 'read index received' (duration: 113.010421ms)","trace[954344166] 'applied index is now lower than readState.Index' (duration: 54.562649ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:54:29.578787Z","caller":"traceutil/trace.go:171","msg":"trace[150047744] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"113.513375ms","start":"2026-06-24T17:54:29.46525Z","end":"2026-06-24T17:54:29.578763Z","steps":["trace[150047744] 'process raft request' (duration: 113.465653ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:54:29.578837Z","caller":"traceutil/trace.go:171","msg":"trace[1056549585] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"143.637089ms","start":"2026-06-24T17:54:29.43516Z","end":"2026-06-24T17:54:29.578797Z","steps":["trace[1056549585] 'process raft request' (duration: 88.67624ms)","trace[1056549585] 'compare' (duration: 54.712223ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:54:37.589839Z","caller":"traceutil/trace.go:171","msg":"trace[323278691] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"167.239306ms","start":"2026-06-24T17:54:37.422519Z","end":"2026-06-24T17:54:37.589758Z","steps":["trace[323278691] 'process raft request' (duration: 167.095922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:54:47.321044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.626483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:54:47.321281Z","caller":"traceutil/trace.go:171","msg":"trace[1488798212] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"357.62454ms","start":"2026-06-24T17:54:46.963626Z","end":"2026-06-24T17:54:47.32125Z","steps":["trace[1488798212] 'process raft request' (duration: 244.988641ms)","trace[1488798212] 'compare' (duration: 111.50349ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:54:47.32139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:54:46.963601Z","time spent":"357.735453ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:55:00.269857Z","caller":"traceutil/trace.go:171","msg":"trace[353003686] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"298.601913ms","start":"2026-06-24T17:54:59.971232Z","end":"2026-06-24T17:55:00.269834Z","steps":["trace[353003686] 'process raft request' (duration: 298.45985ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.277964Z","caller":"traceutil/trace.go:171","msg":"trace[1643216036] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"293.9682ms","start":"2026-06-24T17:54:59.983975Z","end":"2026-06-24T17:55:00.277943Z","steps":["trace[1643216036] 'process raft request' (duration: 293.777745ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.278165Z","caller":"traceutil/trace.go:171","msg":"trace[39836511] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"286.472395ms","start":"2026-06-24T17:54:59.991681Z","end":"2026-06-24T17:55:00.278153Z","steps":["trace[39836511] 'process raft request' (duration: 286.156449ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.278227Z","caller":"traceutil/trace.go:171","msg":"trace[1590927734] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"288.740731ms","start":"2026-06-24T17:54:59.98946Z","end":"2026-06-24T17:55:00.278201Z","steps":["trace[1590927734] 'process raft request' (duration: 288.320171ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.278283Z","caller":"traceutil/trace.go:171","msg":"trace[986335727] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"286.902856ms","start":"2026-06-24T17:54:59.991338Z","end":"2026-06-24T17:55:00.278241Z","steps":["trace[986335727] 'process raft request' (duration: 286.464535ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.278322Z","caller":"traceutil/trace.go:171","msg":"trace[1375503234] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"275.277541ms","start":"2026-06-24T17:55:00.002995Z","end":"2026-06-24T17:55:00.278273Z","steps":["trace[1375503234] 'process raft request' (duration: 274.924262ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.278392Z","caller":"traceutil/trace.go:171","msg":"trace[358576017] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"264.756923ms","start":"2026-06-24T17:55:00.013627Z","end":"2026-06-24T17:55:00.278384Z","steps":["trace[358576017] 'process raft request' (duration: 264.339143ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.278454Z","caller":"traceutil/trace.go:171","msg":"trace[833810498] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"284.731123ms","start":"2026-06-24T17:54:59.993715Z","end":"2026-06-24T17:55:00.278446Z","steps":["trace[833810498] 'process raft request' (duration: 284.167049ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.278532Z","caller":"traceutil/trace.go:171","msg":"trace[1675725262] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"301.166095ms","start":"2026-06-24T17:54:59.977292Z","end":"2026-06-24T17:55:00.278458Z","steps":["trace[1675725262] 'process raft request' (duration: 300.432477ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.278533Z","caller":"traceutil/trace.go:171","msg":"trace[637571674] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"301.110135ms","start":"2026-06-24T17:54:59.97714Z","end":"2026-06-24T17:55:00.278251Z","steps":["trace[637571674] 'process raft request' (duration: 300.48827ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:00.278593Z","caller":"traceutil/trace.go:171","msg":"trace[989445199] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"241.253297ms","start":"2026-06-24T17:55:00.037332Z","end":"2026-06-24T17:55:00.278585Z","steps":["trace[989445199] 'process raft request' (duration: 240.690723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:00.278631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:54:59.977273Z","time spent":"301.296989ms","remote":"127.0.0.1:51358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T17:55:00.278646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:54:59.97713Z","time spent":"301.458604ms","remote":"127.0.0.1:51358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T17:55:00.279318Z","caller":"traceutil/trace.go:171","msg":"trace[320113571] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"133.646702ms","start":"2026-06-24T17:55:00.145658Z","end":"2026-06-24T17:55:00.279305Z","steps":["trace[320113571] 'process raft request' (duration: 132.874643ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:06.672393Z","caller":"traceutil/trace.go:171","msg":"trace[1079625304] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"543.723269ms","start":"2026-06-24T17:55:06.128641Z","end":"2026-06-24T17:55:06.672364Z","steps":["trace[1079625304] 'process raft request' (duration: 543.119464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:06.672654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:06.128618Z","time spent":"543.928805ms","remote":"127.0.0.1:51408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:55:06.672915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.584544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-25vhd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-24T17:55:06.672928Z","caller":"traceutil/trace.go:171","msg":"trace[205219578] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"473.00451ms","start":"2026-06-24T17:55:06.199276Z","end":"2026-06-24T17:55:06.67228Z","steps":["trace[205219578] 'read index received' (duration: 472.197281ms)","trace[205219578] 'applied index is now lower than readState.Index' (duration: 806.279µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:55:06.673062Z","caller":"traceutil/trace.go:171","msg":"trace[2004667691] range","detail":"{range_begin:/registry/pods/kube-system/cilium-25vhd; range_end:; response_count:1; response_revision:631; }","duration":"473.778469ms","start":"2026-06-24T17:55:06.19923Z","end":"2026-06-24T17:55:06.673008Z","steps":["trace[2004667691] 'agreement among raft nodes before linearized reading' (duration: 473.276506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:06.673145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:06.199213Z","time spent":"473.918241ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-25vhd\" "} {"level":"warn","ts":"2026-06-24T17:55:06.673296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.226838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.130\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T17:55:06.673364Z","caller":"traceutil/trace.go:171","msg":"trace[579030100] range","detail":"{range_begin:/registry/masterleases/199.19.213.130; range_end:; response_count:1; response_revision:631; }","duration":"236.354312ms","start":"2026-06-24T17:55:06.436989Z","end":"2026-06-24T17:55:06.673344Z","steps":["trace[579030100] 'agreement among raft nodes before linearized reading' (duration: 236.177488ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:06.890358Z","caller":"traceutil/trace.go:171","msg":"trace[903548997] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"199.302256ms","start":"2026-06-24T17:55:06.691013Z","end":"2026-06-24T17:55:06.890316Z","steps":["trace[903548997] 'process raft request' (duration: 199.069311ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:06.890693Z","caller":"traceutil/trace.go:171","msg":"trace[1206184700] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"163.700416ms","start":"2026-06-24T17:55:06.726934Z","end":"2026-06-24T17:55:06.890634Z","steps":["trace[1206184700] 'process raft request' (duration: 163.317336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:07.949135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.317191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:55:07.949256Z","caller":"traceutil/trace.go:171","msg":"trace[1071142440] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"1.046348618s","start":"2026-06-24T17:55:06.90289Z","end":"2026-06-24T17:55:07.949239Z","steps":["trace[1071142440] 'read index received' (duration: 105.589561ms)","trace[1071142440] 'applied index is now lower than readState.Index' (duration: 940.756777ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:55:07.949349Z","caller":"traceutil/trace.go:171","msg":"trace[2090256569] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.114900303s","start":"2026-06-24T17:55:06.834438Z","end":"2026-06-24T17:55:07.949338Z","steps":["trace[2090256569] 'process raft request' (duration: 175.283856ms)","trace[2090256569] 'compare' (duration: 938.45125ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:55:07.949407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:06.834414Z","time spent":"1.114961756s","remote":"127.0.0.1:51348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:55:07.949687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046783628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-24T17:55:07.949749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.849243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:55:07.949869Z","caller":"traceutil/trace.go:171","msg":"trace[2127356682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"857.017327ms","start":"2026-06-24T17:55:07.092821Z","end":"2026-06-24T17:55:07.949838Z","steps":["trace[2127356682] 'agreement among raft nodes before linearized reading' (duration: 856.656518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:07.949917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021512598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-24T17:55:07.949914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.726258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-24T17:55:07.95001Z","caller":"traceutil/trace.go:171","msg":"trace[376847461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"1.0216229s","start":"2026-06-24T17:55:06.928354Z","end":"2026-06-24T17:55:07.949977Z","steps":["trace[376847461] 'agreement among raft nodes before linearized reading' (duration: 1.021418025s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:07.950081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:06.928333Z","time spent":"1.021734832s","remote":"127.0.0.1:51578","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":"info","ts":"2026-06-24T17:55:07.949811Z","caller":"traceutil/trace.go:171","msg":"trace[139161577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"1.046934512s","start":"2026-06-24T17:55:06.902859Z","end":"2026-06-24T17:55:07.949794Z","steps":["trace[139161577] 'agreement among raft nodes before linearized reading' (duration: 1.046638645s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:07.950188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:06.902847Z","time spent":"1.047325772s","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T17:55:07.950015Z","caller":"traceutil/trace.go:171","msg":"trace[669457089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"919.884672ms","start":"2026-06-24T17:55:07.030103Z","end":"2026-06-24T17:55:07.949988Z","steps":["trace[669457089] 'agreement among raft nodes before linearized reading' (duration: 919.619626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:07.950267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:07.030084Z","time spent":"920.165149ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-24T17:55:07.949691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041016518s","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-24T17:55:07.950373Z","caller":"traceutil/trace.go:171","msg":"trace[943928852] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:634; }","duration":"1.041751246s","start":"2026-06-24T17:55:06.908599Z","end":"2026-06-24T17:55:07.95035Z","steps":["trace[943928852] 'agreement among raft nodes before linearized reading' (duration: 1.040827294s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:07.94993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:07.092804Z","time spent":"857.117009ms","remote":"127.0.0.1:51326","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-24T17:55:07.950411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.875508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-24T17:55:07.950425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:06.908582Z","time spent":"1.041827398s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-06-24T17:55:07.950475Z","caller":"traceutil/trace.go:171","msg":"trace[1451502160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"534.971861ms","start":"2026-06-24T17:55:07.41548Z","end":"2026-06-24T17:55:07.950452Z","steps":["trace[1451502160] 'agreement among raft nodes before linearized reading' (duration: 534.214363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:07.950559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:07.41543Z","time spent":"535.117414ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T17:55:08.458437Z","caller":"traceutil/trace.go:171","msg":"trace[1506309354] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"500.085917ms","start":"2026-06-24T17:55:07.95832Z","end":"2026-06-24T17:55:08.458406Z","steps":["trace[1506309354] 'process raft request' (duration: 413.861312ms)","trace[1506309354] 'compare' (duration: 86.060551ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:55:08.45875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:07.958307Z","time spent":"500.256791ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:55:08.466516Z","caller":"traceutil/trace.go:171","msg":"trace[942074459] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"507.69334ms","start":"2026-06-24T17:55:07.95877Z","end":"2026-06-24T17:55:08.466464Z","steps":["trace[942074459] 'process raft request' (duration: 507.449074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:08.466749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:07.958744Z","time spent":"507.905854ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:55:08.466879Z","caller":"traceutil/trace.go:171","msg":"trace[1864505712] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"507.966826ms","start":"2026-06-24T17:55:07.958884Z","end":"2026-06-24T17:55:08.466851Z","steps":["trace[1864505712] 'process raft request' (duration: 507.531236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:08.467034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:07.958874Z","time spent":"508.074829ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:55:08.467093Z","caller":"traceutil/trace.go:171","msg":"trace[343002041] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"508.167091ms","start":"2026-06-24T17:55:07.958881Z","end":"2026-06-24T17:55:08.467048Z","steps":["trace[343002041] 'process raft request' (duration: 507.719181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:08.467262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:07.958867Z","time spent":"508.307594ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:55:08.467808Z","caller":"traceutil/trace.go:171","msg":"trace[1475367837] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"508.592792ms","start":"2026-06-24T17:55:07.959194Z","end":"2026-06-24T17:55:08.467787Z","steps":["trace[1475367837] 'read index received' (duration: 412.993971ms)","trace[1475367837] 'applied index is now lower than readState.Index' (duration: 95.597601ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:55:08.468077Z","caller":"traceutil/trace.go:171","msg":"trace[1109805331] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"108.559943ms","start":"2026-06-24T17:55:08.359453Z","end":"2026-06-24T17:55:08.468013Z","steps":["trace[1109805331] 'process raft request' (duration: 107.65249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:08.4682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.15888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:55:08.468366Z","caller":"traceutil/trace.go:171","msg":"trace[667758832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"373.344375ms","start":"2026-06-24T17:55:08.095003Z","end":"2026-06-24T17:55:08.468348Z","steps":["trace[667758832] 'agreement among raft nodes before linearized reading' (duration: 373.16878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:08.468438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:08.094977Z","time spent":"373.446657ms","remote":"127.0.0.1:51326","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-24T17:55:08.468129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.92152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-24T17:55:08.468678Z","caller":"traceutil/trace.go:171","msg":"trace[240669894] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:639; }","duration":"509.439931ms","start":"2026-06-24T17:55:07.95917Z","end":"2026-06-24T17:55:08.46861Z","steps":["trace[240669894] 'agreement among raft nodes before linearized reading' (duration: 508.887138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:08.46876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:07.959161Z","time spent":"509.569554ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-06-24T17:55:23.892783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.68013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-t2wkc\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-24T17:55:23.892857Z","caller":"traceutil/trace.go:171","msg":"trace[1474966431] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-t2wkc; range_end:; response_count:1; response_revision:795; }","duration":"580.806893ms","start":"2026-06-24T17:55:23.312036Z","end":"2026-06-24T17:55:23.892843Z","steps":["trace[1474966431] 'range keys from in-memory index tree' (duration: 580.510187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:23.892887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:23.312011Z","time spent":"580.869315ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-t2wkc\" "} {"level":"info","ts":"2026-06-24T17:55:34.95519Z","caller":"traceutil/trace.go:171","msg":"trace[1763361530] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"123.163734ms","start":"2026-06-24T17:55:34.831997Z","end":"2026-06-24T17:55:34.955161Z","steps":["trace[1763361530] 'process raft request' (duration: 114.441494ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:35.23885Z","caller":"traceutil/trace.go:171","msg":"trace[1280156473] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:970; }","duration":"204.804416ms","start":"2026-06-24T17:55:35.034019Z","end":"2026-06-24T17:55:35.238823Z","steps":["trace[1280156473] 'read index received' (duration: 9.97901ms)","trace[1280156473] 'applied index is now lower than readState.Index' (duration: 194.824616ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:55:35.239072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.032871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-786pw\" ","response":"range_response_count:1 size:2650"} {"level":"info","ts":"2026-06-24T17:55:35.239115Z","caller":"traceutil/trace.go:171","msg":"trace[834624400] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-786pw; range_end:; response_count:1; response_revision:939; }","duration":"205.112312ms","start":"2026-06-24T17:55:35.033987Z","end":"2026-06-24T17:55:35.239099Z","steps":["trace[834624400] 'agreement among raft nodes before linearized reading' (duration: 204.960889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:35.239143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.447633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-06-24T17:55:35.239218Z","caller":"traceutil/trace.go:171","msg":"trace[344906823] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:939; }","duration":"152.574314ms","start":"2026-06-24T17:55:35.086617Z","end":"2026-06-24T17:55:35.239191Z","steps":["trace[344906823] 'agreement among raft nodes before linearized reading' (duration: 152.359629ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:35.239073Z","caller":"traceutil/trace.go:171","msg":"trace[526805859] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"205.376658ms","start":"2026-06-24T17:55:35.033529Z","end":"2026-06-24T17:55:35.238906Z","steps":["trace[526805859] 'process raft request' (duration: 114.790617ms)","trace[526805859] 'compare' (duration: 90.334395ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:55:35.422029Z","caller":"traceutil/trace.go:171","msg":"trace[74697778] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"176.131287ms","start":"2026-06-24T17:55:35.245874Z","end":"2026-06-24T17:55:35.422005Z","steps":["trace[74697778] 'process raft request' (duration: 175.964012ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:35.422692Z","caller":"traceutil/trace.go:171","msg":"trace[1850759060] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"143.504648ms","start":"2026-06-24T17:55:35.279166Z","end":"2026-06-24T17:55:35.42267Z","steps":["trace[1850759060] 'process raft request' (duration: 142.798921ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:40.88137Z","caller":"traceutil/trace.go:171","msg":"trace[2140949543] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"150.73112ms","start":"2026-06-24T17:55:40.730613Z","end":"2026-06-24T17:55:40.881344Z","steps":["trace[2140949543] 'process raft request' (duration: 150.556345ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:40.885164Z","caller":"traceutil/trace.go:171","msg":"trace[644608781] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"141.223542ms","start":"2026-06-24T17:55:40.743928Z","end":"2026-06-24T17:55:40.885151Z","steps":["trace[644608781] 'process raft request' (duration: 141.008048ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:55.133037Z","caller":"traceutil/trace.go:171","msg":"trace[697360771] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"331.879575ms","start":"2026-06-24T17:55:54.801136Z","end":"2026-06-24T17:55:55.133016Z","steps":["trace[697360771] 'read index received' (duration: 331.835065ms)","trace[697360771] 'applied index is now lower than readState.Index' (duration: 43.35µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:55:55.133146Z","caller":"traceutil/trace.go:171","msg":"trace[238731899] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"385.57861ms","start":"2026-06-24T17:55:54.747532Z","end":"2026-06-24T17:55:55.133111Z","steps":["trace[238731899] 'process raft request' (duration: 385.332855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:55.133313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:54.747509Z","time spent":"385.707954ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:55:55.133325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.163413ms","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-24T17:55:55.13337Z","caller":"traceutil/trace.go:171","msg":"trace[1544249830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"332.265815ms","start":"2026-06-24T17:55:54.801092Z","end":"2026-06-24T17:55:55.133358Z","steps":["trace[1544249830] 'agreement among raft nodes before linearized reading' (duration: 332.03703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:55.133405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:55:54.801074Z","time spent":"332.323076ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T17:55:55.164023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.553014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-24T17:55:55.16423Z","caller":"traceutil/trace.go:171","msg":"trace[563627116] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"138.124407ms","start":"2026-06-24T17:55:55.026087Z","end":"2026-06-24T17:55:55.164212Z","steps":["trace[563627116] 'process raft request' (duration: 131.325175ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:55:55.164253Z","caller":"traceutil/trace.go:171","msg":"trace[2011624278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"227.84831ms","start":"2026-06-24T17:55:54.936383Z","end":"2026-06-24T17:55:55.164231Z","steps":["trace[2011624278] 'agreement among raft nodes before linearized reading' (duration: 227.486522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:55:55.164156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.825149ms","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-24T17:55:55.164575Z","caller":"traceutil/trace.go:171","msg":"trace[2095687308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1199; }","duration":"228.27833ms","start":"2026-06-24T17:55:54.936283Z","end":"2026-06-24T17:55:55.164562Z","steps":["trace[2095687308] 'agreement among raft nodes before linearized reading' (duration: 227.746788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:11.576372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.8081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:56:11.576604Z","caller":"traceutil/trace.go:171","msg":"trace[848574689] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"217.609006ms","start":"2026-06-24T17:56:11.358968Z","end":"2026-06-24T17:56:11.576577Z","steps":["trace[848574689] 'process raft request' (duration: 76.51588ms)","trace[848574689] 'compare' (duration: 140.700037ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:56:44.147321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.646726ms","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-24T17:56:44.147412Z","caller":"traceutil/trace.go:171","msg":"trace[1313290347] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1887; }","duration":"347.801191ms","start":"2026-06-24T17:56:43.79959Z","end":"2026-06-24T17:56:44.147391Z","steps":["trace[1313290347] 'range keys from in-memory index tree' (duration: 347.400912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:44.147487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:43.799559Z","time spent":"347.916112ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-24T17:56:45.129024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387084994021970670,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T17:56:45.267663Z","caller":"traceutil/trace.go:171","msg":"trace[504057037] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"829.298192ms","start":"2026-06-24T17:56:44.438337Z","end":"2026-06-24T17:56:45.267635Z","steps":["trace[504057037] 'process raft request' (duration: 829.139558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:45.267825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:44.438319Z","time spent":"829.427254ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:56:45.279786Z","caller":"traceutil/trace.go:171","msg":"trace[1348517520] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1961; }","duration":"651.605279ms","start":"2026-06-24T17:56:44.628161Z","end":"2026-06-24T17:56:45.279767Z","steps":["trace[1348517520] 'read index received' (duration: 639.413886ms)","trace[1348517520] 'applied index is now lower than readState.Index' (duration: 12.190743ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:45.279938Z","caller":"traceutil/trace.go:171","msg":"trace[1766500992] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"451.897083ms","start":"2026-06-24T17:56:44.828029Z","end":"2026-06-24T17:56:45.279926Z","steps":["trace[1766500992] 'process raft request' (duration: 451.616227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:45.280117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:44.828002Z","time spent":"452.022008ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:56:45.280255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.107001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-664e4ade-1508-4eb6-a3cd-6e8258a63d19\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-24T17:56:45.280313Z","caller":"traceutil/trace.go:171","msg":"trace[967080680] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-664e4ade-1508-4eb6-a3cd-6e8258a63d19; range_end:; response_count:1; response_revision:1889; }","duration":"652.197073ms","start":"2026-06-24T17:56:44.628097Z","end":"2026-06-24T17:56:45.280294Z","steps":["trace[967080680] 'agreement among raft nodes before linearized reading' (duration: 652.08398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:45.280362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:44.628063Z","time spent":"652.286526ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-664e4ade-1508-4eb6-a3cd-6e8258a63d19\" "} {"level":"warn","ts":"2026-06-24T17:56:45.280148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.120683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-664e4ade-1508-4eb6-a3cd-6e8258a63d19\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-24T17:56:45.280465Z","caller":"traceutil/trace.go:171","msg":"trace[600730200] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-664e4ade-1508-4eb6-a3cd-6e8258a63d19; range_end:; response_count:1; response_revision:1889; }","duration":"591.506722ms","start":"2026-06-24T17:56:44.688934Z","end":"2026-06-24T17:56:45.280441Z","steps":["trace[600730200] 'agreement among raft nodes before linearized reading' (duration: 591.036051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:45.280513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:44.688915Z","time spent":"591.586912ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-664e4ade-1508-4eb6-a3cd-6e8258a63d19\" "} {"level":"warn","ts":"2026-06-24T17:56:45.281002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.159652ms","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-24T17:56:45.281058Z","caller":"traceutil/trace.go:171","msg":"trace[1628538060] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1889; }","duration":"124.219273ms","start":"2026-06-24T17:56:45.156825Z","end":"2026-06-24T17:56:45.281044Z","steps":["trace[1628538060] 'agreement among raft nodes before linearized reading' (duration: 123.490777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.115502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.487156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:56:46.11564Z","caller":"traceutil/trace.go:171","msg":"trace[1096821030] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"672.894829ms","start":"2026-06-24T17:56:45.442718Z","end":"2026-06-24T17:56:46.115613Z","steps":["trace[1096821030] 'process raft request' (duration: 450.199578ms)","trace[1096821030] 'compare' (duration: 222.343812ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:56:46.115735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:45.442685Z","time spent":"672.99871ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:56:46.134557Z","caller":"traceutil/trace.go:171","msg":"trace[1880211011] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"657.078596ms","start":"2026-06-24T17:56:45.477463Z","end":"2026-06-24T17:56:46.134542Z","steps":["trace[1880211011] 'process raft request' (duration: 656.948953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.134633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:45.477436Z","time spent":"657.161077ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:56:46.134704Z","caller":"traceutil/trace.go:171","msg":"trace[1817685630] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"615.999228ms","start":"2026-06-24T17:56:45.518697Z","end":"2026-06-24T17:56:46.134697Z","steps":["trace[1817685630] 'process raft request' (duration: 615.784843ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.134704Z","caller":"traceutil/trace.go:171","msg":"trace[1021963630] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1964; }","duration":"603.855656ms","start":"2026-06-24T17:56:45.530826Z","end":"2026-06-24T17:56:46.134681Z","steps":["trace[1021963630] 'read index received' (duration: 362.102725ms)","trace[1021963630] 'applied index is now lower than readState.Index' (duration: 241.751431ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:56:46.134738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:45.518681Z","time spent":"616.040269ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:56:46.134966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.113931ms","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-24T17:56:46.135051Z","caller":"traceutil/trace.go:171","msg":"trace[1537675860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1893; }","duration":"604.247484ms","start":"2026-06-24T17:56:45.530784Z","end":"2026-06-24T17:56:46.135032Z","steps":["trace[1537675860] 'agreement among raft nodes before linearized reading' (duration: 603.993299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.135102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:45.530754Z","time spent":"604.339504ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T17:56:46.137896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.300564ms","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-24T17:56:46.137997Z","caller":"traceutil/trace.go:171","msg":"trace[586341263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1894; }","duration":"603.430496ms","start":"2026-06-24T17:56:45.534546Z","end":"2026-06-24T17:56:46.137976Z","steps":["trace[586341263] 'agreement among raft nodes before linearized reading' (duration: 603.201061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.138044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:45.534529Z","time spent":"603.503488ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-24T17:56:46.138353Z","caller":"traceutil/trace.go:171","msg":"trace[1106625373] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"241.914086ms","start":"2026-06-24T17:56:45.896424Z","end":"2026-06-24T17:56:46.138338Z","steps":["trace[1106625373] 'process raft request' (duration: 241.212011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.138825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.51848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-24T17:56:46.138878Z","caller":"traceutil/trace.go:171","msg":"trace[545353772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1894; }","duration":"387.602183ms","start":"2026-06-24T17:56:45.751262Z","end":"2026-06-24T17:56:46.138864Z","steps":["trace[545353772] 'agreement among raft nodes before linearized reading' (duration: 387.48066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.138915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:45.751248Z","time spent":"387.657264ms","remote":"127.0.0.1:51578","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-24T17:56:46.139135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.149646ms","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-24T17:56:46.139185Z","caller":"traceutil/trace.go:171","msg":"trace[152147008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1894; }","duration":"601.233827ms","start":"2026-06-24T17:56:45.537937Z","end":"2026-06-24T17:56:46.139171Z","steps":["trace[152147008] 'agreement among raft nodes before linearized reading' (duration: 601.073175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.139217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:45.537914Z","time spent":"601.29562ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T17:56:50.16556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.295054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:56:50.165767Z","caller":"traceutil/trace.go:171","msg":"trace[406390559] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"153.451557ms","start":"2026-06-24T17:56:50.012276Z","end":"2026-06-24T17:56:50.165728Z","steps":["trace[406390559] 'process raft request' (duration: 38.890868ms)","trace[406390559] 'compare' (duration: 114.055078ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:51.444548Z","caller":"traceutil/trace.go:171","msg":"trace[2103313520] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2030; }","duration":"138.710497ms","start":"2026-06-24T17:56:51.305813Z","end":"2026-06-24T17:56:51.444523Z","steps":["trace[2103313520] 'read index received' (duration: 138.538834ms)","trace[2103313520] 'applied index is now lower than readState.Index' (duration: 170.213µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:51.444704Z","caller":"traceutil/trace.go:171","msg":"trace[1661318284] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"144.599944ms","start":"2026-06-24T17:56:51.300086Z","end":"2026-06-24T17:56:51.444686Z","steps":["trace[1661318284] 'process raft request' (duration: 144.268897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:51.445121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.24498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-24T17:56:51.445184Z","caller":"traceutil/trace.go:171","msg":"trace[270689428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1955; }","duration":"139.410332ms","start":"2026-06-24T17:56:51.305758Z","end":"2026-06-24T17:56:51.445168Z","steps":["trace[270689428] 'agreement among raft nodes before linearized reading' (duration: 139.164377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:51.445302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.284496ms","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-24T17:56:51.445407Z","caller":"traceutil/trace.go:171","msg":"trace[468121361] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1955; }","duration":"117.433389ms","start":"2026-06-24T17:56:51.327946Z","end":"2026-06-24T17:56:51.445379Z","steps":["trace[468121361] 'agreement among raft nodes before linearized reading' (duration: 117.111542ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:57.939458Z","caller":"traceutil/trace.go:171","msg":"trace[1832675037] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2067; }","duration":"462.082448ms","start":"2026-06-24T17:56:57.477345Z","end":"2026-06-24T17:56:57.939428Z","steps":["trace[1832675037] 'read index received' (duration: 461.910865ms)","trace[1832675037] 'applied index is now lower than readState.Index' (duration: 169.893µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:57.939628Z","caller":"traceutil/trace.go:171","msg":"trace[1754532404] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"465.536903ms","start":"2026-06-24T17:56:57.474076Z","end":"2026-06-24T17:56:57.939613Z","steps":["trace[1754532404] 'process raft request' (duration: 465.179766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:57.939776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:57.474047Z","time spent":"465.622244ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:56:57.939855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.916853ms","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-24T17:56:57.939958Z","caller":"traceutil/trace.go:171","msg":"trace[1986594531] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1990; }","duration":"453.061175ms","start":"2026-06-24T17:56:57.486869Z","end":"2026-06-24T17:56:57.93993Z","steps":["trace[1986594531] 'agreement among raft nodes before linearized reading' (duration: 452.805599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:57.940032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:57.486851Z","time spent":"453.162207ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-24T17:56:57.940173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.741173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-24T17:56:57.94022Z","caller":"traceutil/trace.go:171","msg":"trace[518779403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1990; }","duration":"462.901327ms","start":"2026-06-24T17:56:57.477306Z","end":"2026-06-24T17:56:57.940208Z","steps":["trace[518779403] 'agreement among raft nodes before linearized reading' (duration: 462.614511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:57.940255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:57.477287Z","time spent":"462.958158ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-24T17:56:58.070158Z","caller":"traceutil/trace.go:171","msg":"trace[1910171261] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"125.864724ms","start":"2026-06-24T17:56:57.944249Z","end":"2026-06-24T17:56:58.070114Z","steps":["trace[1910171261] 'process raft request' (duration: 124.892973ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:57:20.284975Z","caller":"traceutil/trace.go:171","msg":"trace[518845186] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"118.282332ms","start":"2026-06-24T17:57:20.166668Z","end":"2026-06-24T17:57:20.28495Z","steps":["trace[518845186] 'process raft request' (duration: 118.118048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:20.677313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.125729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:57:20.677924Z","caller":"traceutil/trace.go:171","msg":"trace[1832830053] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"424.401216ms","start":"2026-06-24T17:57:20.253477Z","end":"2026-06-24T17:57:20.677879Z","steps":["trace[1832830053] 'process raft request' (duration: 273.628103ms)","trace[1832830053] 'compare' (duration: 149.69857ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:57:20.678127Z","caller":"traceutil/trace.go:171","msg":"trace[19053174] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"393.888126ms","start":"2026-06-24T17:57:20.284125Z","end":"2026-06-24T17:57:20.678013Z","steps":["trace[19053174] 'process raft request' (duration: 393.705582ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:57:20.678204Z","caller":"traceutil/trace.go:171","msg":"trace[163763579] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2212; }","duration":"339.460816ms","start":"2026-06-24T17:57:20.338713Z","end":"2026-06-24T17:57:20.678174Z","steps":["trace[163763579] 'read index received' (duration: 188.424837ms)","trace[163763579] 'applied index is now lower than readState.Index' (duration: 151.033809ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:57:20.678213Z","caller":"traceutil/trace.go:171","msg":"trace[652269296] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"385.830004ms","start":"2026-06-24T17:57:20.292349Z","end":"2026-06-24T17:57:20.678179Z","steps":["trace[652269296] 'process raft request' (duration: 385.609679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:20.67843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:20.28411Z","time spent":"394.212002ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:57:20.678551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.779482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-24T17:57:20.678597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:20.292338Z","time spent":"386.025868ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:57:20.678648Z","caller":"traceutil/trace.go:171","msg":"trace[287189704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2133; }","duration":"339.987367ms","start":"2026-06-24T17:57:20.338637Z","end":"2026-06-24T17:57:20.678624Z","steps":["trace[287189704] 'agreement among raft nodes before linearized reading' (duration: 339.6592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:20.678643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.31782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-24T17:57:20.678701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:20.338616Z","time spent":"340.075699ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-24T17:57:20.678713Z","caller":"traceutil/trace.go:171","msg":"trace[1260871888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2133; }","duration":"330.428153ms","start":"2026-06-24T17:57:20.348264Z","end":"2026-06-24T17:57:20.678692Z","steps":["trace[1260871888] 'agreement among raft nodes before linearized reading' (duration: 330.175398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:20.678766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:20.34825Z","time spent":"330.502594ms","remote":"127.0.0.1:51578","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-24T17:57:20.678903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.43444ms","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-24T17:57:20.678982Z","caller":"traceutil/trace.go:171","msg":"trace[1466434365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2133; }","duration":"328.528541ms","start":"2026-06-24T17:57:20.350438Z","end":"2026-06-24T17:57:20.678967Z","steps":["trace[1466434365] 'agreement among raft nodes before linearized reading' (duration: 328.273136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:20.679049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:20.350429Z","time spent":"328.609334ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T17:57:20.67906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:20.253458Z","time spent":"424.59008ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:57:20.679185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.431258ms","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-24T17:57:20.679227Z","caller":"traceutil/trace.go:171","msg":"trace[1640330443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2133; }","duration":"327.532661ms","start":"2026-06-24T17:57:20.351684Z","end":"2026-06-24T17:57:20.679217Z","steps":["trace[1640330443] 'agreement among raft nodes before linearized reading' (duration: 326.948598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:20.679308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:20.351675Z","time spent":"327.625324ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T17:57:20.896202Z","caller":"traceutil/trace.go:171","msg":"trace[738466967] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"213.054751ms","start":"2026-06-24T17:57:20.68312Z","end":"2026-06-24T17:57:20.896175Z","steps":["trace[738466967] 'process raft request' (duration: 155.352762ms)","trace[738466967] 'compare' (duration: 57.462594ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:57:20.896869Z","caller":"traceutil/trace.go:171","msg":"trace[659243700] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"213.327626ms","start":"2026-06-24T17:57:20.683512Z","end":"2026-06-24T17:57:20.89684Z","steps":["trace[659243700] 'process raft request' (duration: 212.57636ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:57:20.89701Z","caller":"traceutil/trace.go:171","msg":"trace[1792066385] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"213.112433ms","start":"2026-06-24T17:57:20.683876Z","end":"2026-06-24T17:57:20.896988Z","steps":["trace[1792066385] 'process raft request' (duration: 212.866638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:21.24222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.527163ms","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-24T17:57:21.242357Z","caller":"traceutil/trace.go:171","msg":"trace[583146683] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2137; }","duration":"132.708028ms","start":"2026-06-24T17:57:21.109628Z","end":"2026-06-24T17:57:21.242336Z","steps":["trace[583146683] 'range keys from in-memory index tree' (duration: 132.34724ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:57:21.72131Z","caller":"traceutil/trace.go:171","msg":"trace[1816360889] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"119.724632ms","start":"2026-06-24T17:57:21.60155Z","end":"2026-06-24T17:57:21.721274Z","steps":["trace[1816360889] 'read index received' (duration: 119.569018ms)","trace[1816360889] 'applied index is now lower than readState.Index' (duration: 153.984µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:57:21.721505Z","caller":"traceutil/trace.go:171","msg":"trace[661248027] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"222.761057ms","start":"2026-06-24T17:57:21.49869Z","end":"2026-06-24T17:57:21.721451Z","steps":["trace[661248027] 'process raft request' (duration: 222.344889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:21.721602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.030048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:57:21.721693Z","caller":"traceutil/trace.go:171","msg":"trace[226650756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2138; }","duration":"120.177711ms","start":"2026-06-24T17:57:21.601496Z","end":"2026-06-24T17:57:21.721674Z","steps":["trace[226650756] 'agreement among raft nodes before linearized reading' (duration: 119.905085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:22.105479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.315492ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-24T17:57:22.105586Z","caller":"traceutil/trace.go:171","msg":"trace[104428490] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"317.386972ms","start":"2026-06-24T17:57:21.788178Z","end":"2026-06-24T17:57:22.105565Z","steps":["trace[104428490] 'read index received' (duration: 105.988619ms)","trace[104428490] 'applied index is now lower than readState.Index' (duration: 211.396663ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:57:22.105824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.643937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18bc1614f44f28e8\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-24T17:57:22.105864Z","caller":"traceutil/trace.go:171","msg":"trace[1254000581] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18bc1614f44f28e8; range_end:; response_count:1; response_revision:2138; }","duration":"317.736729ms","start":"2026-06-24T17:57:21.788116Z","end":"2026-06-24T17:57:22.105853Z","steps":["trace[1254000581] 'agreement among raft nodes before linearized reading' (duration: 317.535435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:22.105899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:21.78809Z","time spent":"317.800081ms","remote":"127.0.0.1:51404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18bc1614f44f28e8\" "} {"level":"warn","ts":"2026-06-24T17:57:22.132877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.472054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-06-24T17:57:22.132935Z","caller":"traceutil/trace.go:171","msg":"trace[1844720115] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2139; }","duration":"118.573226ms","start":"2026-06-24T17:57:22.014344Z","end":"2026-06-24T17:57:22.132917Z","steps":["trace[1844720115] 'agreement among raft nodes before linearized reading' (duration: 118.368021ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:57:22.491646Z","caller":"traceutil/trace.go:171","msg":"trace[314795310] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"350.360562ms","start":"2026-06-24T17:57:22.141253Z","end":"2026-06-24T17:57:22.491613Z","steps":["trace[314795310] 'read index received' (duration: 335.666069ms)","trace[314795310] 'applied index is now lower than readState.Index' (duration: 14.692953ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:57:22.491823Z","caller":"traceutil/trace.go:171","msg":"trace[1974911733] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"383.691724ms","start":"2026-06-24T17:57:22.10811Z","end":"2026-06-24T17:57:22.491802Z","steps":["trace[1974911733] 'process raft request' (duration: 369.363339ms)","trace[1974911733] 'compare' (duration: 13.613269ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:57:22.49205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:22.108093Z","time spent":"383.770824ms","remote":"127.0.0.1:51404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:57:22.492214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.327611ms","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-24T17:57:22.492281Z","caller":"traceutil/trace.go:171","msg":"trace[451718667] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"242.438174ms","start":"2026-06-24T17:57:22.249824Z","end":"2026-06-24T17:57:22.492263Z","steps":["trace[451718667] 'agreement among raft nodes before linearized reading' (duration: 242.2128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:22.492599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.672218ms","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-24T17:57:22.492652Z","caller":"traceutil/trace.go:171","msg":"trace[1495854397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2140; }","duration":"203.74712ms","start":"2026-06-24T17:57:22.288891Z","end":"2026-06-24T17:57:22.492639Z","steps":["trace[1495854397] 'agreement among raft nodes before linearized reading' (duration: 203.600917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:22.492765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.507627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:57:22.492824Z","caller":"traceutil/trace.go:171","msg":"trace[1642852291] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2140; }","duration":"351.585299ms","start":"2026-06-24T17:57:22.141225Z","end":"2026-06-24T17:57:22.49281Z","steps":["trace[1642852291] 'agreement among raft nodes before linearized reading' (duration: 351.495577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:22.49287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:57:22.141211Z","time spent":"351.649659ms","remote":"127.0.0.1:51766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-24T17:58:26.705148Z","caller":"traceutil/trace.go:171","msg":"trace[269053789] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"258.498261ms","start":"2026-06-24T17:58:26.446628Z","end":"2026-06-24T17:58:26.705126Z","steps":["trace[269053789] 'read index received' (duration: 258.317937ms)","trace[269053789] 'applied index is now lower than readState.Index' (duration: 179.414µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:58:26.705207Z","caller":"traceutil/trace.go:171","msg":"trace[1151793680] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"345.324294ms","start":"2026-06-24T17:58:26.359844Z","end":"2026-06-24T17:58:26.705168Z","steps":["trace[1151793680] 'process raft request' (duration: 345.150549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:26.705323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.348728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:58:26.705363Z","caller":"traceutil/trace.go:171","msg":"trace[2065752909] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:2588; }","duration":"128.408799ms","start":"2026-06-24T17:58:26.576941Z","end":"2026-06-24T17:58:26.70535Z","steps":["trace[2065752909] 'agreement among raft nodes before linearized reading' (duration: 128.326567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:26.705406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:26.359824Z","time spent":"345.456487ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:58:26.705468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.82659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.130\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T17:58:26.705551Z","caller":"traceutil/trace.go:171","msg":"trace[1905535471] range","detail":"{range_begin:/registry/masterleases/199.19.213.130; range_end:; response_count:1; response_revision:2588; }","duration":"258.965554ms","start":"2026-06-24T17:58:26.446566Z","end":"2026-06-24T17:58:26.705531Z","steps":["trace[1905535471] 'agreement among raft nodes before linearized reading' (duration: 258.700817ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:59.103214Z","caller":"traceutil/trace.go:171","msg":"trace[636003275] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"130.886777ms","start":"2026-06-24T17:58:58.972272Z","end":"2026-06-24T17:58:59.103158Z","steps":["trace[636003275] 'process raft request' (duration: 130.564479ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:59.169329Z","caller":"traceutil/trace.go:171","msg":"trace[1133352207] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"127.914931ms","start":"2026-06-24T17:58:59.04139Z","end":"2026-06-24T17:58:59.169305Z","steps":["trace[1133352207] 'process raft request' (duration: 127.864379ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:59.169516Z","caller":"traceutil/trace.go:171","msg":"trace[2062855063] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"156.719421ms","start":"2026-06-24T17:58:59.012788Z","end":"2026-06-24T17:58:59.169507Z","steps":["trace[2062855063] 'process raft request' (duration: 147.563109ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:59.169585Z","caller":"traceutil/trace.go:171","msg":"trace[1053965463] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"133.192743ms","start":"2026-06-24T17:58:59.036385Z","end":"2026-06-24T17:58:59.169578Z","steps":["trace[1053965463] 'process raft request' (duration: 132.768282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:59.815624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.82014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:58:59.815761Z","caller":"traceutil/trace.go:171","msg":"trace[462661914] linearizableReadLoop","detail":"{readStateIndex:2875; appliedIndex:2874; }","duration":"464.410313ms","start":"2026-06-24T17:58:59.351297Z","end":"2026-06-24T17:58:59.815708Z","steps":["trace[462661914] 'read index received' (duration: 43.412379ms)","trace[462661914] 'applied index is now lower than readState.Index' (duration: 420.996444ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:58:59.816026Z","caller":"traceutil/trace.go:171","msg":"trace[1606184419] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"588.874816ms","start":"2026-06-24T17:58:59.227136Z","end":"2026-06-24T17:58:59.816011Z","steps":["trace[1606184419] 'process raft request' (duration: 167.584154ms)","trace[1606184419] 'compare' (duration: 420.439281ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:58:59.816135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:59.227117Z","time spent":"588.947488ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:58:59.816473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.187413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-24T17:58:59.81671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.448113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T17:58:59.816835Z","caller":"traceutil/trace.go:171","msg":"trace[1963352618] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2770; }","duration":"214.647087ms","start":"2026-06-24T17:58:59.602157Z","end":"2026-06-24T17:58:59.816804Z","steps":["trace[1963352618] 'agreement among raft nodes before linearized reading' (duration: 214.327409ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:59.816523Z","caller":"traceutil/trace.go:171","msg":"trace[242738297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2770; }","duration":"465.281615ms","start":"2026-06-24T17:58:59.351231Z","end":"2026-06-24T17:58:59.816513Z","steps":["trace[242738297] 'agreement among raft nodes before linearized reading' (duration: 465.141632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:59.816996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:59.351208Z","time spent":"465.772539ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T17:58:59.816996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.57034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-24T17:58:59.817109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.410095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18bc16296892c972\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-24T17:58:59.817193Z","caller":"traceutil/trace.go:171","msg":"trace[281771535] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18bc16296892c972; range_end:; response_count:1; response_revision:2770; }","duration":"179.527048ms","start":"2026-06-24T17:58:59.637645Z","end":"2026-06-24T17:58:59.817172Z","steps":["trace[281771535] 'agreement among raft nodes before linearized reading' (duration: 179.273931ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:59.817211Z","caller":"traceutil/trace.go:171","msg":"trace[76925644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2770; }","duration":"453.826666ms","start":"2026-06-24T17:58:59.363315Z","end":"2026-06-24T17:58:59.817141Z","steps":["trace[76925644] 'agreement among raft nodes before linearized reading' (duration: 453.349064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:59.817312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:59.363305Z","time spent":"453.99217ms","remote":"127.0.0.1:51578","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":"warn","ts":"2026-06-24T17:58:59.81787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.555216ms","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-24T17:58:59.817955Z","caller":"traceutil/trace.go:171","msg":"trace[279735348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2770; }","duration":"458.687489ms","start":"2026-06-24T17:58:59.359248Z","end":"2026-06-24T17:58:59.817936Z","steps":["trace[279735348] 'agreement among raft nodes before linearized reading' (duration: 457.217772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:59.817999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:59.359228Z","time spent":"458.762471ms","remote":"127.0.0.1:51578","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-24T18:01:20.115988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.99251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:01:20.116085Z","caller":"traceutil/trace.go:171","msg":"trace[2029486023] linearizableReadLoop","detail":"{readStateIndex:3740; appliedIndex:3739; }","duration":"141.860162ms","start":"2026-06-24T18:01:19.974212Z","end":"2026-06-24T18:01:20.116072Z","steps":["trace[2029486023] 'read index received' (duration: 43.26µs)","trace[2029486023] 'applied index is now lower than readState.Index' (duration: 141.815762ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:01:20.116183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.958016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:01:20.116229Z","caller":"traceutil/trace.go:171","msg":"trace[1313530546] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:3602; }","duration":"142.043437ms","start":"2026-06-24T18:01:19.974172Z","end":"2026-06-24T18:01:20.116216Z","steps":["trace[1313530546] 'agreement among raft nodes before linearized reading' (duration: 141.933335ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:01:20.116287Z","caller":"traceutil/trace.go:171","msg":"trace[254844005] transaction","detail":"{read_only:false; response_revision:3602; number_of_response:1; }","duration":"187.162243ms","start":"2026-06-24T18:01:19.929096Z","end":"2026-06-24T18:01:20.116259Z","steps":["trace[254844005] 'compare' (duration: 183.879597ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:01:29.000369Z","caller":"traceutil/trace.go:171","msg":"trace[496530535] linearizableReadLoop","detail":"{readStateIndex:3791; appliedIndex:3790; }","duration":"122.813428ms","start":"2026-06-24T18:01:28.877521Z","end":"2026-06-24T18:01:29.000334Z","steps":["trace[496530535] 'read index received' (duration: 68.474086ms)","trace[496530535] 'applied index is now lower than readState.Index' (duration: 54.337572ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:01:29.000384Z","caller":"traceutil/trace.go:171","msg":"trace[121976402] transaction","detail":"{read_only:false; response_revision:3651; number_of_response:1; }","duration":"127.894183ms","start":"2026-06-24T18:01:28.872449Z","end":"2026-06-24T18:01:29.000343Z","steps":["trace[121976402] 'process raft request' (duration: 73.528771ms)","trace[121976402] 'compare' (duration: 54.141747ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:01:29.000516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.979122ms","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:5"} {"level":"info","ts":"2026-06-24T18:01:29.000705Z","caller":"traceutil/trace.go:171","msg":"trace[686305523] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:3651; }","duration":"123.168385ms","start":"2026-06-24T18:01:28.877476Z","end":"2026-06-24T18:01:29.000644Z","steps":["trace[686305523] 'agreement among raft nodes before linearized reading' (duration: 122.97417ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:01:46.158795Z","caller":"traceutil/trace.go:171","msg":"trace[1029497118] transaction","detail":"{read_only:false; response_revision:3743; number_of_response:1; }","duration":"105.032386ms","start":"2026-06-24T18:01:46.053731Z","end":"2026-06-24T18:01:46.158763Z","steps":["trace[1029497118] 'process raft request' (duration: 26.193156ms)","trace[1029497118] 'compare' (duration: 78.611985ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:01:46.158866Z","caller":"traceutil/trace.go:171","msg":"trace[407267662] transaction","detail":"{read_only:false; response_revision:3744; number_of_response:1; }","duration":"104.538083ms","start":"2026-06-24T18:01:46.054306Z","end":"2026-06-24T18:01:46.158845Z","steps":["trace[407267662] 'process raft request' (duration: 104.38595ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:01:56.389028Z","caller":"traceutil/trace.go:171","msg":"trace[89221414] linearizableReadLoop","detail":"{readStateIndex:4009; appliedIndex:4008; }","duration":"113.275125ms","start":"2026-06-24T18:01:56.275732Z","end":"2026-06-24T18:01:56.389007Z","steps":["trace[89221414] 'read index received' (duration: 69.160846ms)","trace[89221414] 'applied index is now lower than readState.Index' (duration: 44.113629ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:01:56.389143Z","caller":"traceutil/trace.go:171","msg":"trace[440665173] transaction","detail":"{read_only:false; response_revision:3861; number_of_response:1; }","duration":"121.722706ms","start":"2026-06-24T18:01:56.267408Z","end":"2026-06-24T18:01:56.389131Z","steps":["trace[440665173] 'process raft request' (duration: 77.530275ms)","trace[440665173] 'compare' (duration: 43.984386ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:01:56.389485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.729325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-24T18:01:56.389547Z","caller":"traceutil/trace.go:171","msg":"trace[113230255] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3861; }","duration":"113.808277ms","start":"2026-06-24T18:01:56.275721Z","end":"2026-06-24T18:01:56.389529Z","steps":["trace[113230255] 'agreement among raft nodes before linearized reading' (duration: 113.619363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:41.49163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.955243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:02:41.49172Z","caller":"traceutil/trace.go:171","msg":"trace[672217492] linearizableReadLoop","detail":"{readStateIndex:4702; appliedIndex:4701; }","duration":"124.331772ms","start":"2026-06-24T18:02:41.367375Z","end":"2026-06-24T18:02:41.491706Z","steps":["trace[672217492] 'read index received' (duration: 40.851µs)","trace[672217492] 'applied index is now lower than readState.Index' (duration: 124.289681ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:41.491943Z","caller":"traceutil/trace.go:171","msg":"trace[434268278] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"162.305932ms","start":"2026-06-24T18:02:41.329623Z","end":"2026-06-24T18:02:41.491928Z","steps":["trace[434268278] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/replicasets/monitoring/kube-prometheus-stack-operator-cd88cf4bf; req_size:5191; } (duration: 156.664065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:41.492172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.792662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-24T18:02:41.492209Z","caller":"traceutil/trace.go:171","msg":"trace[1761153061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4529; }","duration":"124.856244ms","start":"2026-06-24T18:02:41.367343Z","end":"2026-06-24T18:02:41.4922Z","steps":["trace[1761153061] 'agreement among raft nodes before linearized reading' (duration: 124.749132ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:41.628209Z","caller":"traceutil/trace.go:171","msg":"trace[2009720610] linearizableReadLoop","detail":"{readStateIndex:4704; appliedIndex:4703; }","duration":"107.73224ms","start":"2026-06-24T18:02:41.520448Z","end":"2026-06-24T18:02:41.62818Z","steps":["trace[2009720610] 'read index received' (duration: 92.54943ms)","trace[2009720610] 'applied index is now lower than readState.Index' (duration: 15.18139ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:41.628392Z","caller":"traceutil/trace.go:171","msg":"trace[982335812] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"126.909979ms","start":"2026-06-24T18:02:41.50145Z","end":"2026-06-24T18:02:41.62836Z","steps":["trace[982335812] 'process raft request' (duration: 111.529484ms)","trace[982335812] 'compare' (duration: 15.066698ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:41.628473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.994727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-06-24T18:02:41.628519Z","caller":"traceutil/trace.go:171","msg":"trace[1511991409] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4531; }","duration":"108.086207ms","start":"2026-06-24T18:02:41.520418Z","end":"2026-06-24T18:02:41.628504Z","steps":["trace[1511991409] 'agreement among raft nodes before linearized reading' (duration: 107.905764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:41.653658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.131068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-06-24T18:02:41.653792Z","caller":"traceutil/trace.go:171","msg":"trace[1125468631] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4531; }","duration":"124.295811ms","start":"2026-06-24T18:02:41.529479Z","end":"2026-06-24T18:02:41.653775Z","steps":["trace[1125468631] 'agreement among raft nodes before linearized reading' (duration: 124.043276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:43.909964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.161556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:498802"} {"level":"info","ts":"2026-06-24T18:02:43.910049Z","caller":"traceutil/trace.go:171","msg":"trace[301190186] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:4576; }","duration":"115.300599ms","start":"2026-06-24T18:02:43.79473Z","end":"2026-06-24T18:02:43.91003Z","steps":["trace[301190186] 'range keys from in-memory index tree' (duration: 114.707275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:44.677682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.46172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:02:44.677784Z","caller":"traceutil/trace.go:171","msg":"trace[1892814704] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"230.375582ms","start":"2026-06-24T18:02:44.447387Z","end":"2026-06-24T18:02:44.677762Z","steps":["trace[1892814704] 'process raft request' (duration: 60.770399ms)","trace[1892814704] 'compare' (duration: 169.256525ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:44.678608Z","caller":"traceutil/trace.go:171","msg":"trace[107864850] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"229.746357ms","start":"2026-06-24T18:02:44.44885Z","end":"2026-06-24T18:02:44.678597Z","steps":["trace[107864850] 'process raft request' (duration: 229.43438ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:47.506817Z","caller":"traceutil/trace.go:171","msg":"trace[1668762096] linearizableReadLoop","detail":"{readStateIndex:4807; appliedIndex:4806; }","duration":"118.068518ms","start":"2026-06-24T18:02:47.388728Z","end":"2026-06-24T18:02:47.506796Z","steps":["trace[1668762096] 'read index received' (duration: 104.486405ms)","trace[1668762096] 'applied index is now lower than readState.Index' (duration: 13.581293ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:47.506902Z","caller":"traceutil/trace.go:171","msg":"trace[1481473044] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"118.714903ms","start":"2026-06-24T18:02:47.388178Z","end":"2026-06-24T18:02:47.506893Z","steps":["trace[1481473044] 'process raft request' (duration: 105.021267ms)","trace[1481473044] 'compare' (duration: 13.381188ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:47.507113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.371225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-24T18:02:47.507137Z","caller":"traceutil/trace.go:171","msg":"trace[1489843410] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4631; }","duration":"118.435067ms","start":"2026-06-24T18:02:47.388694Z","end":"2026-06-24T18:02:47.507129Z","steps":["trace[1489843410] 'agreement among raft nodes before linearized reading' (duration: 118.329784ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:47.519712Z","caller":"traceutil/trace.go:171","msg":"trace[612265934] transaction","detail":"{read_only:false; response_revision:4632; number_of_response:1; }","duration":"115.01899ms","start":"2026-06-24T18:02:47.404665Z","end":"2026-06-24T18:02:47.519684Z","steps":["trace[612265934] 'process raft request' (duration: 114.845546ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:48.122589Z","caller":"traceutil/trace.go:171","msg":"trace[221765210] linearizableReadLoop","detail":"{readStateIndex:4829; appliedIndex:4827; }","duration":"142.060904ms","start":"2026-06-24T18:02:47.980512Z","end":"2026-06-24T18:02:48.122573Z","steps":["trace[221765210] 'read index received' (duration: 19.999027ms)","trace[221765210] 'applied index is now lower than readState.Index' (duration: 122.061097ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:48.122597Z","caller":"traceutil/trace.go:171","msg":"trace[978286162] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"142.228747ms","start":"2026-06-24T18:02:47.980339Z","end":"2026-06-24T18:02:48.122568Z","steps":["trace[978286162] 'process raft request' (duration: 138.881072ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:48.122693Z","caller":"traceutil/trace.go:171","msg":"trace[2011838012] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"134.374741ms","start":"2026-06-24T18:02:47.988311Z","end":"2026-06-24T18:02:48.122686Z","steps":["trace[2011838012] 'process raft request' (duration: 134.203508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:48.123004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.468163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-06-24T18:02:48.123081Z","caller":"traceutil/trace.go:171","msg":"trace[1349425259] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4653; }","duration":"142.580965ms","start":"2026-06-24T18:02:47.980483Z","end":"2026-06-24T18:02:48.123064Z","steps":["trace[1349425259] 'agreement among raft nodes before linearized reading' (duration: 142.330699ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:48.128433Z","caller":"traceutil/trace.go:171","msg":"trace[1284314446] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"108.880252ms","start":"2026-06-24T18:02:48.019541Z","end":"2026-06-24T18:02:48.128421Z","steps":["trace[1284314446] 'process raft request' (duration: 108.763939ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:48.295548Z","caller":"traceutil/trace.go:171","msg":"trace[1156544565] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4657; }","duration":"117.246069ms","start":"2026-06-24T18:02:48.178284Z","end":"2026-06-24T18:02:48.29553Z","steps":["trace[1156544565] 'process raft request' (duration: 117.077596ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:48.295693Z","caller":"traceutil/trace.go:171","msg":"trace[315485944] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"118.406745ms","start":"2026-06-24T18:02:48.177204Z","end":"2026-06-24T18:02:48.295611Z","steps":["trace[315485944] 'process raft request' (duration: 55.458188ms)","trace[315485944] 'compare' (duration: 62.386645ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:48.296135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.591091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-06-24T18:02:48.296179Z","caller":"traceutil/trace.go:171","msg":"trace[1625169305] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4658; }","duration":"118.671161ms","start":"2026-06-24T18:02:48.177496Z","end":"2026-06-24T18:02:48.296167Z","steps":["trace[1625169305] 'agreement among raft nodes before linearized reading' (duration: 118.515278ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:48.295964Z","caller":"traceutil/trace.go:171","msg":"trace[2140704845] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"115.07257ms","start":"2026-06-24T18:02:48.180877Z","end":"2026-06-24T18:02:48.295949Z","steps":["trace[2140704845] 'process raft request' (duration: 114.542808ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:48.842236Z","caller":"traceutil/trace.go:171","msg":"trace[652855734] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4849; }","duration":"149.319335ms","start":"2026-06-24T18:02:48.692891Z","end":"2026-06-24T18:02:48.84221Z","steps":["trace[652855734] 'read index received' (duration: 21.05384ms)","trace[652855734] 'applied index is now lower than readState.Index' (duration: 128.264515ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:48.842299Z","caller":"traceutil/trace.go:171","msg":"trace[160207917] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"149.389017ms","start":"2026-06-24T18:02:48.692871Z","end":"2026-06-24T18:02:48.84226Z","steps":["trace[160207917] 'process raft request' (duration: 122.755272ms)","trace[160207917] 'compare' (duration: 26.451291ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:48.842465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.547871ms","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-24T18:02:48.842545Z","caller":"traceutil/trace.go:171","msg":"trace[475084748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4671; }","duration":"149.677574ms","start":"2026-06-24T18:02:48.692856Z","end":"2026-06-24T18:02:48.842534Z","steps":["trace[475084748] 'agreement among raft nodes before linearized reading' (duration: 149.448899ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:49.527761Z","caller":"traceutil/trace.go:171","msg":"trace[737405498] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"132.195423ms","start":"2026-06-24T18:02:49.395542Z","end":"2026-06-24T18:02:49.527738Z","steps":["trace[737405498] 'process raft request' (duration: 132.010719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:53.545748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.086231ms","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-24T18:02:53.54582Z","caller":"traceutil/trace.go:171","msg":"trace[1972325894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4698; }","duration":"181.210704ms","start":"2026-06-24T18:02:53.364591Z","end":"2026-06-24T18:02:53.545802Z","steps":["trace[1972325894] 'range keys from in-memory index tree' (duration: 180.919668ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:59.051921Z","caller":"traceutil/trace.go:171","msg":"trace[1936733052] linearizableReadLoop","detail":"{readStateIndex:4949; appliedIndex:4948; }","duration":"349.062512ms","start":"2026-06-24T18:02:58.702832Z","end":"2026-06-24T18:02:59.051894Z","steps":["trace[1936733052] 'read index received' (duration: 346.082425ms)","trace[1936733052] 'applied index is now lower than readState.Index' (duration: 2.978457ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:59.051964Z","caller":"traceutil/trace.go:171","msg":"trace[111125543] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"350.117506ms","start":"2026-06-24T18:02:58.701813Z","end":"2026-06-24T18:02:59.051931Z","steps":["trace[111125543] 'process raft request' (duration: 347.192091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.052999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:58.701802Z","time spent":"351.070168ms","remote":"127.0.0.1:51642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T18:02:59.052253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.40257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-06-24T18:02:59.053244Z","caller":"traceutil/trace.go:171","msg":"trace[1110811020] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4767; }","duration":"350.385732ms","start":"2026-06-24T18:02:58.702791Z","end":"2026-06-24T18:02:59.053177Z","steps":["trace[1110811020] 'agreement among raft nodes before linearized reading' (duration: 349.254637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.053292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:58.702774Z","time spent":"350.504015ms","remote":"127.0.0.1:51508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1309,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"info","ts":"2026-06-24T18:02:59.380884Z","caller":"traceutil/trace.go:171","msg":"trace[766571677] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4949; }","duration":"328.203526ms","start":"2026-06-24T18:02:59.052658Z","end":"2026-06-24T18:02:59.380862Z","steps":["trace[766571677] 'read index received' (duration: 318.007569ms)","trace[766571677] 'applied index is now lower than readState.Index' (duration: 10.195047ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:59.38108Z","caller":"traceutil/trace.go:171","msg":"trace[1939448561] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"630.154189ms","start":"2026-06-24T18:02:58.750916Z","end":"2026-06-24T18:02:59.38107Z","steps":["trace[1939448561] 'process raft request' (duration: 619.735737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.381166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:58.750889Z","time spent":"630.21382ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:59.381459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.118762ms","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-24T18:02:59.381511Z","caller":"traceutil/trace.go:171","msg":"trace[2133017294] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"482.103597ms","start":"2026-06-24T18:02:58.899393Z","end":"2026-06-24T18:02:59.381496Z","steps":["trace[2133017294] 'process raft request' (duration: 481.416382ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:59.381559Z","caller":"traceutil/trace.go:171","msg":"trace[530300264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4769; }","duration":"479.275804ms","start":"2026-06-24T18:02:58.902259Z","end":"2026-06-24T18:02:59.381535Z","steps":["trace[530300264] 'agreement among raft nodes before linearized reading' (duration: 478.979557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.381587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:58.899369Z","time spent":"482.179078ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:59.381616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:58.902247Z","time spent":"479.353006ms","remote":"127.0.0.1:51578","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-24T18:02:59.381982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.400973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-06-24T18:02:59.382024Z","caller":"traceutil/trace.go:171","msg":"trace[1792270045] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4769; }","duration":"547.485694ms","start":"2026-06-24T18:02:58.834529Z","end":"2026-06-24T18:02:59.382014Z","steps":["trace[1792270045] 'agreement among raft nodes before linearized reading' (duration: 546.759009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.382055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:58.83448Z","time spent":"547.566147ms","remote":"127.0.0.1:51422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2206997,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-06-24T18:02:59.38529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.711686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T18:02:59.385338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.353855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:59.38537Z","caller":"traceutil/trace.go:171","msg":"trace[683848056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4770; }","duration":"291.415686ms","start":"2026-06-24T18:02:59.093945Z","end":"2026-06-24T18:02:59.385361Z","steps":["trace[683848056] 'agreement among raft nodes before linearized reading' (duration: 291.361805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.385411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.138957ms","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-24T18:02:59.38544Z","caller":"traceutil/trace.go:171","msg":"trace[1226176265] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:0; response_revision:4770; }","duration":"327.828508ms","start":"2026-06-24T18:02:59.057529Z","end":"2026-06-24T18:02:59.385357Z","steps":["trace[1226176265] 'agreement among raft nodes before linearized reading' (duration: 327.682885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.385665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.283151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-06-24T18:02:59.385698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.057516Z","time spent":"328.164515ms","remote":"127.0.0.1:51782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-06-24T18:02:59.385727Z","caller":"traceutil/trace.go:171","msg":"trace[327180555] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4770; }","duration":"325.368074ms","start":"2026-06-24T18:02:59.060344Z","end":"2026-06-24T18:02:59.385712Z","steps":["trace[327180555] 'agreement among raft nodes before linearized reading' (duration: 325.22171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.385772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.060331Z","time spent":"325.428374ms","remote":"127.0.0.1:51508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1309,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"info","ts":"2026-06-24T18:02:59.385474Z","caller":"traceutil/trace.go:171","msg":"trace[673802986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4770; }","duration":"111.23962ms","start":"2026-06-24T18:02:59.274219Z","end":"2026-06-24T18:02:59.385459Z","steps":["trace[673802986] 'agreement among raft nodes before linearized reading' (duration: 111.062337ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:59.385519Z","caller":"traceutil/trace.go:171","msg":"trace[252528370] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"326.451218ms","start":"2026-06-24T18:02:59.059055Z","end":"2026-06-24T18:02:59.385506Z","steps":["trace[252528370] 'process raft request' (duration: 326.018898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.386294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.059043Z","time spent":"327.204414ms","remote":"127.0.0.1:51404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T18:02:59.385605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.256141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-24T18:02:59.386389Z","caller":"traceutil/trace.go:171","msg":"trace[1874738326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4770; }","duration":"209.038609ms","start":"2026-06-24T18:02:59.177332Z","end":"2026-06-24T18:02:59.386371Z","steps":["trace[1874738326] 'agreement among raft nodes before linearized reading' (duration: 208.15078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.887972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.936037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:02:59.888143Z","caller":"traceutil/trace.go:171","msg":"trace[1883611261] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"315.228177ms","start":"2026-06-24T18:02:59.572893Z","end":"2026-06-24T18:02:59.888122Z","steps":["trace[1883611261] 'process raft request' (duration: 102.058795ms)","trace[1883611261] 'compare' (duration: 212.597379ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:59.888221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.572882Z","time spent":"315.296108ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:02:59.888531Z","caller":"traceutil/trace.go:171","msg":"trace[1940728113] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"315.535054ms","start":"2026-06-24T18:02:59.572965Z","end":"2026-06-24T18:02:59.8885Z","steps":["trace[1940728113] 'process raft request' (duration: 315.098535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.88866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.57296Z","time spent":"315.628036ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:02:59.889087Z","caller":"traceutil/trace.go:171","msg":"trace[552766447] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"313.150641ms","start":"2026-06-24T18:02:59.575922Z","end":"2026-06-24T18:02:59.889072Z","steps":["trace[552766447] 'process raft request' (duration: 313.085329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.889218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.575904Z","time spent":"313.274384ms","remote":"127.0.0.1:51404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T18:02:59.889245Z","caller":"traceutil/trace.go:171","msg":"trace[1832991313] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"313.430687ms","start":"2026-06-24T18:02:59.575789Z","end":"2026-06-24T18:02:59.88922Z","steps":["trace[1832991313] 'process raft request' (duration: 312.524187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.890754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.57578Z","time spent":"314.90212ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:02:59.889359Z","caller":"traceutil/trace.go:171","msg":"trace[1053682015] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"313.478838ms","start":"2026-06-24T18:02:59.575872Z","end":"2026-06-24T18:02:59.88935Z","steps":["trace[1053682015] 'process raft request' (duration: 312.909496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:59.891582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.575866Z","time spent":"315.038573ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:03:00.037884Z","caller":"traceutil/trace.go:171","msg":"trace[306570208] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"461.57218ms","start":"2026-06-24T18:02:59.576274Z","end":"2026-06-24T18:03:00.037846Z","steps":["trace[306570208] 'process raft request' (duration: 413.333674ms)","trace[306570208] 'compare' (duration: 47.98659ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:03:00.038144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.576255Z","time spent":"461.787324ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:03:00.038192Z","caller":"traceutil/trace.go:171","msg":"trace[128783630] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"457.848556ms","start":"2026-06-24T18:02:59.58032Z","end":"2026-06-24T18:03:00.038169Z","steps":["trace[128783630] 'process raft request' (duration: 457.424646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.038297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.580299Z","time spent":"457.950519ms","remote":"127.0.0.1:51404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T18:03:00.038331Z","caller":"traceutil/trace.go:171","msg":"trace[1218381593] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"457.15853ms","start":"2026-06-24T18:02:59.581164Z","end":"2026-06-24T18:03:00.038323Z","steps":["trace[1218381593] 'process raft request' (duration: 456.924105ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:00.038275Z","caller":"traceutil/trace.go:171","msg":"trace[248621161] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"114.715387ms","start":"2026-06-24T18:02:59.923536Z","end":"2026-06-24T18:03:00.038251Z","steps":["trace[248621161] 'process raft request' (duration: 114.659545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.03843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.581152Z","time spent":"457.220232ms","remote":"127.0.0.1:51404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T18:03:00.038557Z","caller":"traceutil/trace.go:171","msg":"trace[2023991550] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"456.459236ms","start":"2026-06-24T18:02:59.58209Z","end":"2026-06-24T18:03:00.038549Z","steps":["trace[2023991550] 'process raft request' (duration: 456.050706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.038628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.582084Z","time spent":"456.498536ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T18:03:00.038737Z","caller":"traceutil/trace.go:171","msg":"trace[1302352349] linearizableReadLoop","detail":"{readStateIndex:4969; appliedIndex:4963; }","duration":"462.289845ms","start":"2026-06-24T18:02:59.576432Z","end":"2026-06-24T18:03:00.038722Z","steps":["trace[1302352349] 'read index received' (duration: 98.448165ms)","trace[1302352349] 'applied index is now lower than readState.Index' (duration: 363.83998ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:03:00.038865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.261464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-07e6ea82-079e-44b3-8cb3-4ad18baece9e\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-06-24T18:03:00.038911Z","caller":"traceutil/trace.go:171","msg":"trace[341417488] range","detail":"{range_begin:/registry/persistentvolumes/pvc-07e6ea82-079e-44b3-8cb3-4ad18baece9e; range_end:; response_count:1; response_revision:4791; }","duration":"466.350245ms","start":"2026-06-24T18:02:59.572549Z","end":"2026-06-24T18:03:00.038899Z","steps":["trace[341417488] 'agreement among raft nodes before linearized reading' (duration: 466.216463ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.038938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.57253Z","time spent":"466.401248ms","remote":"127.0.0.1:51474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1474,"request content":"key:\"/registry/persistentvolumes/pvc-07e6ea82-079e-44b3-8cb3-4ad18baece9e\" "} {"level":"warn","ts":"2026-06-24T18:03:00.038991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.832294ms","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-24T18:03:00.039063Z","caller":"traceutil/trace.go:171","msg":"trace[1893950282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4791; }","duration":"439.929087ms","start":"2026-06-24T18:02:59.599117Z","end":"2026-06-24T18:03:00.039047Z","steps":["trace[1893950282] 'agreement among raft nodes before linearized reading' (duration: 439.727893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.039089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.587505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"warn","ts":"2026-06-24T18:03:00.038996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.326646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-06-24T18:03:00.039158Z","caller":"traceutil/trace.go:171","msg":"trace[1145186022] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4791; }","duration":"454.669625ms","start":"2026-06-24T18:02:59.584474Z","end":"2026-06-24T18:03:00.039144Z","steps":["trace[1145186022] 'agreement among raft nodes before linearized reading' (duration: 454.550713ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:00.039185Z","caller":"traceutil/trace.go:171","msg":"trace[1908296902] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4791; }","duration":"462.520971ms","start":"2026-06-24T18:02:59.576632Z","end":"2026-06-24T18:03:00.039153Z","steps":["trace[1908296902] 'agreement among raft nodes before linearized reading' (duration: 462.210664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.03922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.584469Z","time spent":"454.743787ms","remote":"127.0.0.1:51408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":674,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"warn","ts":"2026-06-24T18:03:00.039229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.576626Z","time spent":"462.590052ms","remote":"127.0.0.1:51408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-06-24T18:03:00.039002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.656686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-24T18:03:00.039117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.599108Z","time spent":"439.995569ms","remote":"127.0.0.1:51578","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":"info","ts":"2026-06-24T18:03:00.039278Z","caller":"traceutil/trace.go:171","msg":"trace[1012900699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4791; }","duration":"341.981013ms","start":"2026-06-24T18:02:59.697284Z","end":"2026-06-24T18:03:00.039265Z","steps":["trace[1012900699] 'agreement among raft nodes before linearized reading' (duration: 341.556374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.039327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:59.697266Z","time spent":"342.050405ms","remote":"127.0.0.1:51578","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-24T18:03:00.305198Z","caller":"traceutil/trace.go:171","msg":"trace[1330326392] linearizableReadLoop","detail":"{readStateIndex:4978; appliedIndex:4977; }","duration":"215.873771ms","start":"2026-06-24T18:03:00.089303Z","end":"2026-06-24T18:03:00.305177Z","steps":["trace[1330326392] 'read index received' (duration: 214.131652ms)","trace[1330326392] 'applied index is now lower than readState.Index' (duration: 1.741229ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:03:00.305272Z","caller":"traceutil/trace.go:171","msg":"trace[1810477219] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"216.402293ms","start":"2026-06-24T18:03:00.088863Z","end":"2026-06-24T18:03:00.305265Z","steps":["trace[1810477219] 'process raft request' (duration: 214.557882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.305431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.120517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-06-24T18:03:00.305453Z","caller":"traceutil/trace.go:171","msg":"trace[1052460864] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4796; }","duration":"216.187219ms","start":"2026-06-24T18:03:00.08926Z","end":"2026-06-24T18:03:00.305447Z","steps":["trace[1052460864] 'agreement among raft nodes before linearized reading' (duration: 216.078177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.307724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.459734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-06-24T18:03:00.307868Z","caller":"traceutil/trace.go:171","msg":"trace[537750422] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4798; }","duration":"216.628937ms","start":"2026-06-24T18:03:00.091219Z","end":"2026-06-24T18:03:00.307848Z","steps":["trace[537750422] 'agreement among raft nodes before linearized reading' (duration: 216.32493ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:00.308184Z","caller":"traceutil/trace.go:171","msg":"trace[1904387996] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"206.178305ms","start":"2026-06-24T18:03:00.101992Z","end":"2026-06-24T18:03:00.30817Z","steps":["trace[1904387996] 'process raft request' (duration: 205.477239ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:00.308174Z","caller":"traceutil/trace.go:171","msg":"trace[764325818] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"214.360647ms","start":"2026-06-24T18:03:00.093802Z","end":"2026-06-24T18:03:00.308163Z","steps":["trace[764325818] 'process raft request' (duration: 213.56381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.308365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.52046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:03:00.308453Z","caller":"traceutil/trace.go:171","msg":"trace[268904404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4798; }","duration":"213.635542ms","start":"2026-06-24T18:03:00.094798Z","end":"2026-06-24T18:03:00.308434Z","steps":["trace[268904404] 'agreement among raft nodes before linearized reading' (duration: 213.470848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:00.308725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.46156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:03:00.308814Z","caller":"traceutil/trace.go:171","msg":"trace[445628132] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4798; }","duration":"131.593144ms","start":"2026-06-24T18:03:00.177207Z","end":"2026-06-24T18:03:00.3088Z","steps":["trace[445628132] 'agreement among raft nodes before linearized reading' (duration: 131.478211ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:01.481808Z","caller":"traceutil/trace.go:171","msg":"trace[1326037438] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"133.645698ms","start":"2026-06-24T18:03:01.348126Z","end":"2026-06-24T18:03:01.481772Z","steps":["trace[1326037438] 'process raft request' (duration: 133.391432ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:01.493103Z","caller":"traceutil/trace.go:171","msg":"trace[1038165945] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"105.676615ms","start":"2026-06-24T18:03:01.38738Z","end":"2026-06-24T18:03:01.493057Z","steps":["trace[1038165945] 'process raft request' (duration: 105.420399ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:01.493301Z","caller":"traceutil/trace.go:171","msg":"trace[88207857] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"104.928459ms","start":"2026-06-24T18:03:01.388355Z","end":"2026-06-24T18:03:01.493283Z","steps":["trace[88207857] 'process raft request' (duration: 104.599441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:02.996998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.42555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:03:02.997073Z","caller":"traceutil/trace.go:171","msg":"trace[1558666890] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4837; }","duration":"395.535052ms","start":"2026-06-24T18:03:02.601519Z","end":"2026-06-24T18:03:02.997054Z","steps":["trace[1558666890] 'range keys from in-memory index tree' (duration: 395.348718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:02.997108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:03:02.601501Z","time spent":"395.598033ms","remote":"127.0.0.1:51766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-24T18:03:14.48809Z","caller":"traceutil/trace.go:171","msg":"trace[1355793138] linearizableReadLoop","detail":"{readStateIndex:5169; appliedIndex:5168; }","duration":"158.32267ms","start":"2026-06-24T18:03:14.329743Z","end":"2026-06-24T18:03:14.488066Z","steps":["trace[1355793138] 'read index received' (duration: 158.125036ms)","trace[1355793138] 'applied index is now lower than readState.Index' (duration: 196.694µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:03:14.488312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.548565ms","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-24T18:03:14.488353Z","caller":"traceutil/trace.go:171","msg":"trace[1312611582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4981; }","duration":"158.642467ms","start":"2026-06-24T18:03:14.3297Z","end":"2026-06-24T18:03:14.488342Z","steps":["trace[1312611582] 'agreement among raft nodes before linearized reading' (duration: 158.494363ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:14.488273Z","caller":"traceutil/trace.go:171","msg":"trace[1966213950] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"323.87133ms","start":"2026-06-24T18:03:14.164269Z","end":"2026-06-24T18:03:14.48814Z","steps":["trace[1966213950] 'process raft request' (duration: 323.651725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:14.488594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:03:14.164244Z","time spent":"324.235368ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:03:14.488602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.525141ms","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-24T18:03:14.488799Z","caller":"traceutil/trace.go:171","msg":"trace[1263442976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4981; }","duration":"152.759616ms","start":"2026-06-24T18:03:14.336022Z","end":"2026-06-24T18:03:14.488782Z","steps":["trace[1263442976] 'agreement among raft nodes before linearized reading' (duration: 152.361397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:14.714035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.565151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:03:14.714148Z","caller":"traceutil/trace.go:171","msg":"trace[811498866] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"221.105096ms","start":"2026-06-24T18:03:14.493031Z","end":"2026-06-24T18:03:14.714136Z","steps":["trace[811498866] 'process raft request' (duration: 100.388572ms)","trace[811498866] 'compare' (duration: 120.464808ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:03:14.714476Z","caller":"traceutil/trace.go:171","msg":"trace[1194934467] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"193.892721ms","start":"2026-06-24T18:03:14.520578Z","end":"2026-06-24T18:03:14.714471Z","steps":["trace[1194934467] 'process raft request' (duration: 193.528672ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:14.851983Z","caller":"traceutil/trace.go:171","msg":"trace[1242333088] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"126.550763ms","start":"2026-06-24T18:03:14.725402Z","end":"2026-06-24T18:03:14.851953Z","steps":["trace[1242333088] 'process raft request' (duration: 105.375142ms)","trace[1242333088] 'compare' (duration: 21.047938ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:03:15.275655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.976296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:03:15.27575Z","caller":"traceutil/trace.go:171","msg":"trace[1189645844] linearizableReadLoop","detail":"{readStateIndex:5176; appliedIndex:5175; }","duration":"188.996501ms","start":"2026-06-24T18:03:15.086739Z","end":"2026-06-24T18:03:15.275736Z","steps":["trace[1189645844] 'read index received' (duration: 10.814441ms)","trace[1189645844] 'applied index is now lower than readState.Index' (duration: 178.18078ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:03:15.275841Z","caller":"traceutil/trace.go:171","msg":"trace[1113659744] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"198.782738ms","start":"2026-06-24T18:03:15.077029Z","end":"2026-06-24T18:03:15.275812Z","steps":["trace[1113659744] 'process raft request' (duration: 20.572217ms)","trace[1113659744] 'compare' (duration: 177.819662ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:03:15.275954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.208126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"warn","ts":"2026-06-24T18:03:15.276201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.400045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:03:15.276254Z","caller":"traceutil/trace.go:171","msg":"trace[1681075654] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4988; }","duration":"129.483048ms","start":"2026-06-24T18:03:15.146757Z","end":"2026-06-24T18:03:15.27624Z","steps":["trace[1681075654] 'agreement among raft nodes before linearized reading' (duration: 129.401006ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:15.276544Z","caller":"traceutil/trace.go:171","msg":"trace[342297678] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4988; }","duration":"189.82665ms","start":"2026-06-24T18:03:15.086708Z","end":"2026-06-24T18:03:15.276534Z","steps":["trace[342297678] 'agreement among raft nodes before linearized reading' (duration: 189.109473ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:23.99966Z","caller":"traceutil/trace.go:171","msg":"trace[1280331664] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"126.709503ms","start":"2026-06-24T18:03:23.872924Z","end":"2026-06-24T18:03:23.999633Z","steps":["trace[1280331664] 'process raft request' (duration: 126.59849ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:23.999697Z","caller":"traceutil/trace.go:171","msg":"trace[1582834046] linearizableReadLoop","detail":"{readStateIndex:5250; appliedIndex:5247; }","duration":"125.083877ms","start":"2026-06-24T18:03:23.874593Z","end":"2026-06-24T18:03:23.999677Z","steps":["trace[1582834046] 'read index received' (duration: 119.907152ms)","trace[1582834046] 'applied index is now lower than readState.Index' (duration: 5.175775ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:03:23.999705Z","caller":"traceutil/trace.go:171","msg":"trace[1834356452] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"126.772995ms","start":"2026-06-24T18:03:23.872891Z","end":"2026-06-24T18:03:23.999664Z","steps":["trace[1834356452] 'process raft request' (duration: 121.521818ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:23.999831Z","caller":"traceutil/trace.go:171","msg":"trace[1637927753] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"126.866957ms","start":"2026-06-24T18:03:23.872922Z","end":"2026-06-24T18:03:23.999789Z","steps":["trace[1637927753] 'process raft request' (duration: 126.671182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:23.999915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.345463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus.18bc1660cef90ba1\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-06-24T18:03:23.999966Z","caller":"traceutil/trace.go:171","msg":"trace[655768414] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus.18bc1660cef90ba1; range_end:; response_count:1; response_revision:5060; }","duration":"125.512076ms","start":"2026-06-24T18:03:23.874443Z","end":"2026-06-24T18:03:23.999955Z","steps":["trace[655768414] 'agreement among raft nodes before linearized reading' (duration: 125.311511ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:26.890777Z","caller":"traceutil/trace.go:171","msg":"trace[308142887] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"106.030383ms","start":"2026-06-24T18:03:26.784718Z","end":"2026-06-24T18:03:26.890748Z","steps":["trace[308142887] 'process raft request' (duration: 35.005497ms)","trace[308142887] 'compare' (duration: 70.869312ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:03:43.982785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.807943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:03:43.982878Z","caller":"traceutil/trace.go:171","msg":"trace[1413080202] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5175; }","duration":"162.944916ms","start":"2026-06-24T18:03:43.819912Z","end":"2026-06-24T18:03:43.982857Z","steps":["trace[1413080202] 'range keys from in-memory index tree' (duration: 162.755673ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:43.98295Z","caller":"traceutil/trace.go:171","msg":"trace[1856932902] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"132.683287ms","start":"2026-06-24T18:03:43.850222Z","end":"2026-06-24T18:03:43.982905Z","steps":["trace[1856932902] 'process raft request' (duration: 130.290124ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:48.507865Z","caller":"traceutil/trace.go:171","msg":"trace[169663099] linearizableReadLoop","detail":"{readStateIndex:5395; appliedIndex:5394; }","duration":"304.161418ms","start":"2026-06-24T18:03:48.203678Z","end":"2026-06-24T18:03:48.507839Z","steps":["trace[169663099] 'read index received' (duration: 303.956963ms)","trace[169663099] 'applied index is now lower than readState.Index' (duration: 203.135µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:03:48.508133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.179047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-24T18:03:48.508189Z","caller":"traceutil/trace.go:171","msg":"trace[1558633420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5200; }","duration":"285.302211ms","start":"2026-06-24T18:03:48.222873Z","end":"2026-06-24T18:03:48.508176Z","steps":["trace[1558633420] 'agreement among raft nodes before linearized reading' (duration: 285.151827ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:48.508193Z","caller":"traceutil/trace.go:171","msg":"trace[1935219257] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"313.503055ms","start":"2026-06-24T18:03:48.194674Z","end":"2026-06-24T18:03:48.508177Z","steps":["trace[1935219257] 'process raft request' (duration: 312.974073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:48.508382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.70244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-24T18:03:48.508434Z","caller":"traceutil/trace.go:171","msg":"trace[692092775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5200; }","duration":"304.739531ms","start":"2026-06-24T18:03:48.203658Z","end":"2026-06-24T18:03:48.508398Z","steps":["trace[692092775] 'agreement among raft nodes before linearized reading' (duration: 304.652019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:48.508388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:03:48.194653Z","time spent":"313.582316ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:03:48.508457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:03:48.203651Z","time spent":"304.798842ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T18:04:10.963094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.719639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:04:10.963253Z","caller":"traceutil/trace.go:171","msg":"trace[1992222491] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"248.025072ms","start":"2026-06-24T18:04:10.715195Z","end":"2026-06-24T18:04:10.96322Z","steps":["trace[1992222491] 'process raft request' (duration: 124.066077ms)","trace[1992222491] 'compare' (duration: 123.569926ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:04:10.963334Z","caller":"traceutil/trace.go:171","msg":"trace[1879787078] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"148.20836ms","start":"2026-06-24T18:04:10.815105Z","end":"2026-06-24T18:04:10.963313Z","steps":["trace[1879787078] 'process raft request' (duration: 148.127148ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:10.963381Z","caller":"traceutil/trace.go:171","msg":"trace[1376135068] linearizableReadLoop","detail":"{readStateIndex:5644; appliedIndex:5643; }","duration":"179.180643ms","start":"2026-06-24T18:04:10.784138Z","end":"2026-06-24T18:04:10.963319Z","steps":["trace[1376135068] 'read index received' (duration: 55.134826ms)","trace[1376135068] 'applied index is now lower than readState.Index' (duration: 124.040417ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:04:10.963717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.54372ms","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-24T18:04:10.963759Z","caller":"traceutil/trace.go:171","msg":"trace[1899164203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5444; }","duration":"179.657973ms","start":"2026-06-24T18:04:10.784092Z","end":"2026-06-24T18:04:10.96375Z","steps":["trace[1899164203] 'agreement among raft nodes before linearized reading' (duration: 179.392167ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:11.196099Z","caller":"traceutil/trace.go:171","msg":"trace[217581360] linearizableReadLoop","detail":"{readStateIndex:5647; appliedIndex:5645; }","duration":"166.941212ms","start":"2026-06-24T18:04:11.029122Z","end":"2026-06-24T18:04:11.196063Z","steps":["trace[217581360] 'read index received' (duration: 11.842932ms)","trace[217581360] 'applied index is now lower than readState.Index' (duration: 155.09668ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:04:11.196358Z","caller":"traceutil/trace.go:171","msg":"trace[1194041012] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"172.346812ms","start":"2026-06-24T18:04:11.023983Z","end":"2026-06-24T18:04:11.196329Z","steps":["trace[1194041012] 'process raft request' (duration: 154.692272ms)","trace[1194041012] 'compare' (duration: 17.117558ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:04:11.19663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.490384ms","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-24T18:04:11.197481Z","caller":"traceutil/trace.go:171","msg":"trace[428461092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5446; }","duration":"168.370184ms","start":"2026-06-24T18:04:11.029083Z","end":"2026-06-24T18:04:11.197453Z","steps":["trace[428461092] 'agreement among raft nodes before linearized reading' (duration: 167.206368ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:11.236816Z","caller":"traceutil/trace.go:171","msg":"trace[2147302310] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"159.5784ms","start":"2026-06-24T18:04:11.077214Z","end":"2026-06-24T18:04:11.236793Z","steps":["trace[2147302310] 'process raft request' (duration: 159.419717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:11.236899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.746368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:04:11.237064Z","caller":"traceutil/trace.go:171","msg":"trace[2125195478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5447; }","duration":"142.934472ms","start":"2026-06-24T18:04:11.09411Z","end":"2026-06-24T18:04:11.237045Z","steps":["trace[2125195478] 'agreement among raft nodes before linearized reading' (duration: 142.685667ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:11.42945Z","caller":"traceutil/trace.go:171","msg":"trace[1786254891] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"134.562999ms","start":"2026-06-24T18:04:11.294861Z","end":"2026-06-24T18:04:11.429424Z","steps":["trace[1786254891] 'process raft request' (duration: 101.004758ms)","trace[1786254891] 'compare' (duration: 33.452248ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:04:11.905602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.0186ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-06-24T18:04:12.196586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.204003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:04:12.196697Z","caller":"traceutil/trace.go:171","msg":"trace[1078607504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5450; }","duration":"102.346097ms","start":"2026-06-24T18:04:12.094326Z","end":"2026-06-24T18:04:12.196672Z","steps":["trace[1078607504] 'range keys from in-memory index tree' (duration: 102.138252ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:12.249176Z","caller":"traceutil/trace.go:171","msg":"trace[1338845171] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"104.369722ms","start":"2026-06-24T18:04:12.144755Z","end":"2026-06-24T18:04:12.249125Z","steps":["trace[1338845171] 'process raft request' (duration: 104.180467ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:23.533319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2898} {"level":"info","ts":"2026-06-24T18:04:23.631132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2898,"took":"96.122197ms","hash":103294556,"current-db-size-bytes":54218752,"current-db-size":"54 MB","current-db-size-in-use-bytes":28979200,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-24T18:04:23.631191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":103294556,"revision":2898,"compact-revision":-1} {"level":"info","ts":"2026-06-24T18:05:17.837373Z","caller":"traceutil/trace.go:171","msg":"trace[1397426061] transaction","detail":"{read_only:false; response_revision:5893; number_of_response:1; }","duration":"118.117211ms","start":"2026-06-24T18:05:17.719225Z","end":"2026-06-24T18:05:17.837343Z","steps":["trace[1397426061] 'process raft request' (duration: 117.817234ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:31.670718Z","caller":"traceutil/trace.go:171","msg":"trace[752408386] transaction","detail":"{read_only:false; response_revision:5959; number_of_response:1; }","duration":"165.056697ms","start":"2026-06-24T18:05:31.505642Z","end":"2026-06-24T18:05:31.670699Z","steps":["trace[752408386] 'process raft request' (duration: 164.096946ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:07:00.218704Z","caller":"traceutil/trace.go:171","msg":"trace[361136355] linearizableReadLoop","detail":"{readStateIndex:6846; appliedIndex:6845; }","duration":"123.314751ms","start":"2026-06-24T18:07:00.095363Z","end":"2026-06-24T18:07:00.218678Z","steps":["trace[361136355] 'read index received' (duration: 109.280854ms)","trace[361136355] 'applied index is now lower than readState.Index' (duration: 14.032627ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:07:00.218791Z","caller":"traceutil/trace.go:171","msg":"trace[144605318] transaction","detail":"{read_only:false; response_revision:6605; number_of_response:1; }","duration":"126.45817ms","start":"2026-06-24T18:07:00.09229Z","end":"2026-06-24T18:07:00.218748Z","steps":["trace[144605318] 'process raft request' (duration: 112.412554ms)","trace[144605318] 'compare' (duration: 13.700159ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:07:00.218833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.456256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:07:00.218869Z","caller":"traceutil/trace.go:171","msg":"trace[1860028326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6605; }","duration":"123.537277ms","start":"2026-06-24T18:07:00.09532Z","end":"2026-06-24T18:07:00.218858Z","steps":["trace[1860028326] 'agreement among raft nodes before linearized reading' (duration: 123.460816ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:07:01.137971Z","caller":"traceutil/trace.go:171","msg":"trace[1703912679] transaction","detail":"{read_only:false; response_revision:6610; number_of_response:1; }","duration":"155.899443ms","start":"2026-06-24T18:07:00.982046Z","end":"2026-06-24T18:07:01.137945Z","steps":["trace[1703912679] 'process raft request' (duration: 155.76677ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:07:26.420431Z","caller":"traceutil/trace.go:171","msg":"trace[211133596] transaction","detail":"{read_only:false; response_revision:6799; number_of_response:1; }","duration":"129.063635ms","start":"2026-06-24T18:07:26.291345Z","end":"2026-06-24T18:07:26.420408Z","steps":["trace[211133596] 'process raft request' (duration: 128.913652ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:07:26.708242Z","caller":"traceutil/trace.go:171","msg":"trace[1517520086] linearizableReadLoop","detail":"{readStateIndex:7051; appliedIndex:7049; }","duration":"230.10157ms","start":"2026-06-24T18:07:26.478126Z","end":"2026-06-24T18:07:26.708228Z","steps":["trace[1517520086] 'read index received' (duration: 25.325752ms)","trace[1517520086] 'applied index is now lower than readState.Index' (duration: 204.775048ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:07:26.708412Z","caller":"traceutil/trace.go:171","msg":"trace[1131626022] transaction","detail":"{read_only:false; response_revision:6801; number_of_response:1; }","duration":"282.714327ms","start":"2026-06-24T18:07:26.425654Z","end":"2026-06-24T18:07:26.708369Z","steps":["trace[1131626022] 'process raft request' (duration: 256.68663ms)","trace[1131626022] 'compare' (duration: 25.659779ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:07:26.708932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.791924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.130\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T18:07:26.708994Z","caller":"traceutil/trace.go:171","msg":"trace[2098145319] range","detail":"{range_begin:/registry/masterleases/199.19.213.130; range_end:; response_count:1; response_revision:6801; }","duration":"230.891726ms","start":"2026-06-24T18:07:26.478088Z","end":"2026-06-24T18:07:26.70898Z","steps":["trace[2098145319] 'agreement among raft nodes before linearized reading' (duration: 230.738652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:07:26.779501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.318753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-24T18:07:26.779581Z","caller":"traceutil/trace.go:171","msg":"trace[1720209179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6802; }","duration":"230.453647ms","start":"2026-06-24T18:07:26.549113Z","end":"2026-06-24T18:07:26.779566Z","steps":["trace[1720209179] 'agreement among raft nodes before linearized reading' (duration: 230.263023ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:09:23.540103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5541} {"level":"info","ts":"2026-06-24T18:09:23.622459Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5541,"took":"80.849499ms","hash":4125056796,"current-db-size-bytes":54218752,"current-db-size":"54 MB","current-db-size-in-use-bytes":18513920,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-24T18:09:23.622535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4125056796,"revision":5541,"compact-revision":2898} {"level":"info","ts":"2026-06-24T18:10:05.184298Z","caller":"traceutil/trace.go:171","msg":"trace[1408784256] transaction","detail":"{read_only:false; response_revision:7626; number_of_response:1; }","duration":"110.999706ms","start":"2026-06-24T18:10:05.073268Z","end":"2026-06-24T18:10:05.184268Z","steps":["trace[1408784256] 'process raft request' (duration: 110.768081ms)"],"step_count":1}