{"level":"warn","ts":"2026-06-23T04:38:45.57071Z","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-23T04:38:45.57083Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-23T04:38:45.570909Z","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-23T04:38:45.57092Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-23T04:38:45.570936Z","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-23T04:38:45.571656Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-06-23T04:38:45.571753Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-23T04:38:45.576723Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.878733ms"} {"level":"info","ts":"2026-06-23T04:38:45.582002Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-06-23T04:38:45.582088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-23T04:38:45.582113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-06-23T04:38:45.582125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-23T04:38:45.58215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-06-23T04:38:45.582195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-06-23T04:38:45.585814Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-23T04:38:45.587438Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-23T04:38:45.588837Z","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-23T04:38:45.591097Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-23T04:38:45.591246Z","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-23T04:38:45.591328Z","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-23T04:38:45.591337Z","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-23T04:38:45.591344Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-23T04:38:45.59224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-06-23T04:38:45.592408Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-23T04:38:45.595608Z","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-23T04:38:45.595763Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-23T04:38:45.595826Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-23T04:38:45.596055Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-23T04:38:45.596091Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-23T04:38:46.183635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-23T04:38:46.183702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-23T04:38:46.183717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-06-23T04:38:46.18373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-06-23T04:38:46.183736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-23T04:38:46.183744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-06-23T04:38:46.183753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-23T04:38:46.184899Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-06-23T04:38:46.185023Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T04:38:46.185001Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T04:38:46.185338Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T04:38:46.186614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-23T04:38:46.186726Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T04:38:46.186886Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T04:38:46.186927Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T04:38:46.189967Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-06-23T04:38:46.190051Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-23T04:38:46.190062Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-23T04:38:55.776507Z","caller":"traceutil/trace.go:171","msg":"trace[98988338] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:365; }","duration":"103.637058ms","start":"2026-06-23T04:38:55.672843Z","end":"2026-06-23T04:38:55.77648Z","steps":["trace[98988338] 'read index received' (duration: 43.777356ms)","trace[98988338] 'applied index is now lower than readState.Index' (duration: 59.858442ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:38:55.776622Z","caller":"traceutil/trace.go:171","msg":"trace[362653388] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"131.879784ms","start":"2026-06-23T04:38:55.644711Z","end":"2026-06-23T04:38:55.776591Z","steps":["trace[362653388] 'process raft request' (duration: 131.7143ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:55.776653Z","caller":"traceutil/trace.go:171","msg":"trace[1883904812] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"132.029348ms","start":"2026-06-23T04:38:55.644593Z","end":"2026-06-23T04:38:55.776622Z","steps":["trace[1883904812] 'process raft request' (duration: 72.094204ms)","trace[1883904812] 'compare' (duration: 59.573184ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:38:55.776706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.856684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-06-23T04:38:55.776773Z","caller":"traceutil/trace.go:171","msg":"trace[1572332730] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:353; }","duration":"103.966547ms","start":"2026-06-23T04:38:55.672794Z","end":"2026-06-23T04:38:55.776761Z","steps":["trace[1572332730] 'agreement among raft nodes before linearized reading' (duration: 103.829833ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:56.0309Z","caller":"traceutil/trace.go:171","msg":"trace[1040149091] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"247.389093ms","start":"2026-06-23T04:38:55.783491Z","end":"2026-06-23T04:38:56.03088Z","steps":["trace[1040149091] 'process raft request' (duration: 155.72667ms)","trace[1040149091] 'compare' (duration: 91.492998ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:38:56.030931Z","caller":"traceutil/trace.go:171","msg":"trace[890997722] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:367; }","duration":"208.331647ms","start":"2026-06-23T04:38:55.822579Z","end":"2026-06-23T04:38:56.030911Z","steps":["trace[890997722] 'read index received' (duration: 53.407541ms)","trace[890997722] 'applied index is now lower than readState.Index' (duration: 154.923216ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:38:56.030992Z","caller":"traceutil/trace.go:171","msg":"trace[1839551602] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"246.351863ms","start":"2026-06-23T04:38:55.784599Z","end":"2026-06-23T04:38:56.03095Z","steps":["trace[1839551602] 'process raft request' (duration: 246.230359ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:56.031269Z","caller":"traceutil/trace.go:171","msg":"trace[595738841] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"244.028105ms","start":"2026-06-23T04:38:55.787229Z","end":"2026-06-23T04:38:56.031257Z","steps":["trace[595738841] 'process raft request' (duration: 243.639203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:38:56.03134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.741009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-23T04:38:56.031401Z","caller":"traceutil/trace.go:171","msg":"trace[1749300007] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"149.471284ms","start":"2026-06-23T04:38:55.88191Z","end":"2026-06-23T04:38:56.031381Z","steps":["trace[1749300007] 'process raft request' (duration: 149.398182ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:56.031431Z","caller":"traceutil/trace.go:171","msg":"trace[822238336] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:359; }","duration":"208.897934ms","start":"2026-06-23T04:38:55.82251Z","end":"2026-06-23T04:38:56.031408Z","steps":["trace[822238336] 'agreement among raft nodes before linearized reading' (duration: 208.713599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:38:56.031434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.410419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-23T04:38:56.031483Z","caller":"traceutil/trace.go:171","msg":"trace[1565949759] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:361; }","duration":"158.510962ms","start":"2026-06-23T04:38:55.872958Z","end":"2026-06-23T04:38:56.031469Z","steps":["trace[1565949759] 'agreement among raft nodes before linearized reading' (duration: 158.402899ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:56.031608Z","caller":"traceutil/trace.go:171","msg":"trace[1704336921] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"150.460324ms","start":"2026-06-23T04:38:55.881137Z","end":"2026-06-23T04:38:56.031597Z","steps":["trace[1704336921] 'process raft request' (duration: 150.107523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:38:56.031615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.763109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-23T04:38:56.031646Z","caller":"traceutil/trace.go:171","msg":"trace[2021696099] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:361; }","duration":"108.830742ms","start":"2026-06-23T04:38:55.922806Z","end":"2026-06-23T04:38:56.031637Z","steps":["trace[2021696099] 'agreement among raft nodes before linearized reading' (duration: 108.77466ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:56.031663Z","caller":"traceutil/trace.go:171","msg":"trace[1102601936] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"151.554036ms","start":"2026-06-23T04:38:55.880089Z","end":"2026-06-23T04:38:56.031643Z","steps":["trace[1102601936] 'process raft request' (duration: 151.097283ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:56.319175Z","caller":"traceutil/trace.go:171","msg":"trace[1155645025] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"103.046414ms","start":"2026-06-23T04:38:56.216105Z","end":"2026-06-23T04:38:56.319151Z","steps":["trace[1155645025] 'process raft request' (duration: 102.998313ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:56.319364Z","caller":"traceutil/trace.go:171","msg":"trace[1292887586] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"112.536836ms","start":"2026-06-23T04:38:56.206813Z","end":"2026-06-23T04:38:56.31935Z","steps":["trace[1292887586] 'process raft request' (duration: 112.171485ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:56.319501Z","caller":"traceutil/trace.go:171","msg":"trace[208284581] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"114.216446ms","start":"2026-06-23T04:38:56.205256Z","end":"2026-06-23T04:38:56.319473Z","steps":["trace[208284581] 'process raft request' (duration: 112.899957ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:38:56.319834Z","caller":"traceutil/trace.go:171","msg":"trace[479075649] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"104.432355ms","start":"2026-06-23T04:38:56.215334Z","end":"2026-06-23T04:38:56.319766Z","steps":["trace[479075649] 'process raft request' (duration: 103.705864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:04.464136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.47324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T04:39:04.464261Z","caller":"traceutil/trace.go:171","msg":"trace[2027587274] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"630.822755ms","start":"2026-06-23T04:39:03.833418Z","end":"2026-06-23T04:39:04.464241Z","steps":["trace[2027587274] 'read index received' (duration: 47.422µs)","trace[2027587274] 'applied index is now lower than readState.Index' (duration: 630.773643ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:39:04.464302Z","caller":"traceutil/trace.go:171","msg":"trace[1186629201] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"931.117772ms","start":"2026-06-23T04:39:03.533158Z","end":"2026-06-23T04:39:04.464275Z","steps":["trace[1186629201] 'process raft request' (duration: 260.748905ms)","trace[1186629201] 'compare' (duration: 669.323646ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:39:04.464425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:03.533142Z","time spent":"931.206915ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T04:39:04.464494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.078702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-06-23T04:39:04.464571Z","caller":"traceutil/trace.go:171","msg":"trace[639043508] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:418; }","duration":"631.166306ms","start":"2026-06-23T04:39:03.833362Z","end":"2026-06-23T04:39:04.464528Z","steps":["trace[639043508] 'agreement among raft nodes before linearized reading' (duration: 630.98766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:04.464631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:03.833331Z","time spent":"631.288308ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-06-23T04:39:04.861569Z","caller":"traceutil/trace.go:171","msg":"trace[1587491172] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"380.126177ms","start":"2026-06-23T04:39:04.481376Z","end":"2026-06-23T04:39:04.861502Z","steps":["trace[1587491172] 'process raft request' (duration: 376.353294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:04.861798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:04.481359Z","time spent":"380.334904ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:39:04.866967Z","caller":"traceutil/trace.go:171","msg":"trace[619071254] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"150.568846ms","start":"2026-06-23T04:39:04.71637Z","end":"2026-06-23T04:39:04.866939Z","steps":["trace[619071254] 'read index received' (duration: 141.376631ms)","trace[619071254] 'applied index is now lower than readState.Index' (duration: 9.190565ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:39:04.867206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.798563ms","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-23T04:39:04.867298Z","caller":"traceutil/trace.go:171","msg":"trace[84093992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"150.957508ms","start":"2026-06-23T04:39:04.716321Z","end":"2026-06-23T04:39:04.867279Z","steps":["trace[84093992] 'agreement among raft nodes before linearized reading' (duration: 150.764492ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:05.034059Z","caller":"traceutil/trace.go:171","msg":"trace[1360855502] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"139.851507ms","start":"2026-06-23T04:39:04.894169Z","end":"2026-06-23T04:39:05.03402Z","steps":["trace[1360855502] 'process raft request' (duration: 97.477358ms)","trace[1360855502] 'compare' (duration: 42.108821ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:39:05.034207Z","caller":"traceutil/trace.go:171","msg":"trace[503954953] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"139.921999ms","start":"2026-06-23T04:39:04.894241Z","end":"2026-06-23T04:39:05.034163Z","steps":["trace[503954953] 'process raft request' (duration: 139.712952ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:05.034249Z","caller":"traceutil/trace.go:171","msg":"trace[1761922029] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"138.558078ms","start":"2026-06-23T04:39:04.895647Z","end":"2026-06-23T04:39:05.034205Z","steps":["trace[1761922029] 'process raft request' (duration: 138.396263ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:05.034741Z","caller":"traceutil/trace.go:171","msg":"trace[253676362] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"138.240339ms","start":"2026-06-23T04:39:04.89649Z","end":"2026-06-23T04:39:05.034731Z","steps":["trace[253676362] 'process raft request' (duration: 137.63259ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:05.034989Z","caller":"traceutil/trace.go:171","msg":"trace[265928473] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"138.189917ms","start":"2026-06-23T04:39:04.89676Z","end":"2026-06-23T04:39:05.03495Z","steps":["trace[265928473] 'process raft request' (duration: 137.712003ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.539502Z","caller":"traceutil/trace.go:171","msg":"trace[715231660] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"143.405138ms","start":"2026-06-23T04:39:20.396063Z","end":"2026-06-23T04:39:20.539468Z","steps":["trace[715231660] 'process raft request' (duration: 143.217812ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.560662Z","caller":"traceutil/trace.go:171","msg":"trace[1413874008] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"149.591616ms","start":"2026-06-23T04:39:20.411008Z","end":"2026-06-23T04:39:20.560599Z","steps":["trace[1413874008] 'process raft request' (duration: 149.275907ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.560807Z","caller":"traceutil/trace.go:171","msg":"trace[1647053320] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"148.862914ms","start":"2026-06-23T04:39:20.411859Z","end":"2026-06-23T04:39:20.560722Z","steps":["trace[1647053320] 'process raft request' (duration: 148.600716ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.560887Z","caller":"traceutil/trace.go:171","msg":"trace[1880304082] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"138.307132ms","start":"2026-06-23T04:39:20.422491Z","end":"2026-06-23T04:39:20.560798Z","steps":["trace[1880304082] 'process raft request' (duration: 138.161038ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.561005Z","caller":"traceutil/trace.go:171","msg":"trace[1603807289] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"137.136626ms","start":"2026-06-23T04:39:20.423859Z","end":"2026-06-23T04:39:20.560996Z","steps":["trace[1603807289] 'process raft request' (duration: 136.902719ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.56092Z","caller":"traceutil/trace.go:171","msg":"trace[149890323] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"137.129696ms","start":"2026-06-23T04:39:20.423766Z","end":"2026-06-23T04:39:20.560896Z","steps":["trace[149890323] 'process raft request' (duration: 136.949741ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.561214Z","caller":"traceutil/trace.go:171","msg":"trace[906693771] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"136.526448ms","start":"2026-06-23T04:39:20.424678Z","end":"2026-06-23T04:39:20.561204Z","steps":["trace[906693771] 'process raft request' (duration: 136.131466ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.561173Z","caller":"traceutil/trace.go:171","msg":"trace[48938896] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"135.756384ms","start":"2026-06-23T04:39:20.425399Z","end":"2026-06-23T04:39:20.561156Z","steps":["trace[48938896] 'process raft request' (duration: 135.515457ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.561245Z","caller":"traceutil/trace.go:171","msg":"trace[1197621634] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"119.762027ms","start":"2026-06-23T04:39:20.441464Z","end":"2026-06-23T04:39:20.561226Z","steps":["trace[1197621634] 'process raft request' (duration: 119.545181ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:20.561221Z","caller":"traceutil/trace.go:171","msg":"trace[670338599] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:540; }","duration":"116.083055ms","start":"2026-06-23T04:39:20.445102Z","end":"2026-06-23T04:39:20.561185Z","steps":["trace[670338599] 'read index received' (duration: 94.189308ms)","trace[670338599] 'applied index is now lower than readState.Index' (duration: 21.892817ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:39:20.561341Z","caller":"traceutil/trace.go:171","msg":"trace[1398517781] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"126.261335ms","start":"2026-06-23T04:39:20.435063Z","end":"2026-06-23T04:39:20.561325Z","steps":["trace[1398517781] 'process raft request' (duration: 125.903544ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:20.561388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.2584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T04:39:20.561439Z","caller":"traceutil/trace.go:171","msg":"trace[2104656811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"116.370254ms","start":"2026-06-23T04:39:20.445052Z","end":"2026-06-23T04:39:20.561422Z","steps":["trace[2104656811] 'agreement among raft nodes before linearized reading' (duration: 116.253021ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:22.408102Z","caller":"traceutil/trace.go:171","msg":"trace[1448736000] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"529.644189ms","start":"2026-06-23T04:39:21.878427Z","end":"2026-06-23T04:39:22.408071Z","steps":["trace[1448736000] 'read index received' (duration: 484.264534ms)","trace[1448736000] 'applied index is now lower than readState.Index' (duration: 45.378065ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:39:22.408427Z","caller":"traceutil/trace.go:171","msg":"trace[318635453] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"552.562827ms","start":"2026-06-23T04:39:21.855814Z","end":"2026-06-23T04:39:22.408377Z","steps":["trace[318635453] 'process raft request' (duration: 507.226484ms)","trace[318635453] 'compare' (duration: 44.55299ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:39:22.408476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.01373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-06-23T04:39:22.408518Z","caller":"traceutil/trace.go:171","msg":"trace[275336272] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:612; }","duration":"530.129974ms","start":"2026-06-23T04:39:21.878375Z","end":"2026-06-23T04:39:22.408505Z","steps":["trace[275336272] 'agreement among raft nodes before linearized reading' (duration: 529.823665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:22.408576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:21.878354Z","time spent":"530.213116ms","remote":"127.0.0.1:58988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-23T04:39:22.408612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:21.855788Z","time spent":"552.722643ms","remote":"127.0.0.1:58988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:39:22.416108Z","caller":"traceutil/trace.go:171","msg":"trace[498016434] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"527.104872ms","start":"2026-06-23T04:39:21.888931Z","end":"2026-06-23T04:39:22.416036Z","steps":["trace[498016434] 'process raft request' (duration: 526.804683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:22.416352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:21.888898Z","time spent":"527.339019ms","remote":"127.0.0.1:58988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T04:39:22.416855Z","caller":"traceutil/trace.go:171","msg":"trace[881843803] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"467.880356ms","start":"2026-06-23T04:39:21.948945Z","end":"2026-06-23T04:39:22.416825Z","steps":["trace[881843803] 'process raft request' (duration: 467.079582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:22.417027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:21.948912Z","time spent":"468.031861ms","remote":"127.0.0.1:58988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:39:22.417179Z","caller":"traceutil/trace.go:171","msg":"trace[1000736557] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"337.306013ms","start":"2026-06-23T04:39:22.079849Z","end":"2026-06-23T04:39:22.417155Z","steps":["trace[1000736557] 'process raft request' (duration: 336.908001ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:22.417254Z","caller":"traceutil/trace.go:171","msg":"trace[1721550956] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"132.827353ms","start":"2026-06-23T04:39:22.284399Z","end":"2026-06-23T04:39:22.417227Z","steps":["trace[1721550956] 'process raft request' (duration: 132.787022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:22.417285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:22.079822Z","time spent":"337.415967ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:39:22.417274Z","caller":"traceutil/trace.go:171","msg":"trace[668189067] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"332.319261ms","start":"2026-06-23T04:39:22.084929Z","end":"2026-06-23T04:39:22.417248Z","steps":["trace[668189067] 'process raft request' (duration: 332.157196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:22.417396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:22.084921Z","time spent":"332.421704ms","remote":"127.0.0.1:58988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T04:39:23.076259Z","caller":"traceutil/trace.go:171","msg":"trace[303010838] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"192.122215ms","start":"2026-06-23T04:39:22.884104Z","end":"2026-06-23T04:39:23.076226Z","steps":["trace[303010838] 'process raft request' (duration: 191.903589ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:28.979998Z","caller":"traceutil/trace.go:171","msg":"trace[54345469] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"290.079864ms","start":"2026-06-23T04:39:28.689898Z","end":"2026-06-23T04:39:28.979978Z","steps":["trace[54345469] 'process raft request' (duration: 289.9518ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:29.085724Z","caller":"traceutil/trace.go:171","msg":"trace[979355841] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"148.8112ms","start":"2026-06-23T04:39:28.936875Z","end":"2026-06-23T04:39:29.085686Z","steps":["trace[979355841] 'process raft request' (duration: 143.736494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:29.307968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.00837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T04:39:29.308233Z","caller":"traceutil/trace.go:171","msg":"trace[768206071] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:712; }","duration":"134.799031ms","start":"2026-06-23T04:39:29.173408Z","end":"2026-06-23T04:39:29.308207Z","steps":["trace[768206071] 'read index received' (duration: 24.40908ms)","trace[768206071] 'applied index is now lower than readState.Index' (duration: 110.387831ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:39:29.308236Z","caller":"traceutil/trace.go:171","msg":"trace[104120231] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"185.495358ms","start":"2026-06-23T04:39:29.122711Z","end":"2026-06-23T04:39:29.308206Z","steps":["trace[104120231] 'process raft request' (duration: 75.119588ms)","trace[104120231] 'compare' (duration: 109.681209ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:39:29.308469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.065429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-23T04:39:29.30845Z","caller":"traceutil/trace.go:171","msg":"trace[562042497] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"183.534118ms","start":"2026-06-23T04:39:29.124883Z","end":"2026-06-23T04:39:29.308417Z","steps":["trace[562042497] 'process raft request' (duration: 183.259409ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:39:29.308512Z","caller":"traceutil/trace.go:171","msg":"trace[58024036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"135.132391ms","start":"2026-06-23T04:39:29.173369Z","end":"2026-06-23T04:39:29.308501Z","steps":["trace[58024036] 'agreement among raft nodes before linearized reading' (duration: 134.912594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:39:29.817819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:39:29.514974Z","time spent":"302.815993ms","remote":"127.0.0.1:58970","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-23T04:39:43.686621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.830706ms","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-23T04:39:43.68676Z","caller":"traceutil/trace.go:171","msg":"trace[1472628693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"146.086324ms","start":"2026-06-23T04:39:43.540653Z","end":"2026-06-23T04:39:43.686739Z","steps":["trace[1472628693] 'range keys from in-memory index tree' (duration: 145.603449ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:40:17.199338Z","caller":"traceutil/trace.go:171","msg":"trace[1218928699] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"107.424043ms","start":"2026-06-23T04:40:17.09189Z","end":"2026-06-23T04:40:17.199314Z","steps":["trace[1218928699] 'process raft request' (duration: 107.30158ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:40:39.904625Z","caller":"traceutil/trace.go:171","msg":"trace[2057567844] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"156.399866ms","start":"2026-06-23T04:40:39.748188Z","end":"2026-06-23T04:40:39.904588Z","steps":["trace[2057567844] 'process raft request' (duration: 156.139418ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:40:39.906317Z","caller":"traceutil/trace.go:171","msg":"trace[770838152] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1411; }","duration":"155.27063ms","start":"2026-06-23T04:40:39.751016Z","end":"2026-06-23T04:40:39.906287Z","steps":["trace[770838152] 'read index received' (duration: 153.319517ms)","trace[770838152] 'applied index is now lower than readState.Index' (duration: 1.949933ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:40:39.906426Z","caller":"traceutil/trace.go:171","msg":"trace[912444081] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"156.436217ms","start":"2026-06-23T04:40:39.749957Z","end":"2026-06-23T04:40:39.906393Z","steps":["trace[912444081] 'process raft request' (duration: 156.183429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:40:39.906664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.620491ms","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-23T04:40:39.906726Z","caller":"traceutil/trace.go:171","msg":"trace[970171236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1368; }","duration":"155.731404ms","start":"2026-06-23T04:40:39.750974Z","end":"2026-06-23T04:40:39.906705Z","steps":["trace[970171236] 'agreement among raft nodes before linearized reading' (duration: 155.459016ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:40:48.782752Z","caller":"traceutil/trace.go:171","msg":"trace[350504474] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1547; }","duration":"432.118936ms","start":"2026-06-23T04:40:48.35061Z","end":"2026-06-23T04:40:48.782729Z","steps":["trace[350504474] 'read index received' (duration: 431.969961ms)","trace[350504474] 'applied index is now lower than readState.Index' (duration: 147.375µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:40:48.782932Z","caller":"traceutil/trace.go:171","msg":"trace[796593190] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"619.398597ms","start":"2026-06-23T04:40:48.163479Z","end":"2026-06-23T04:40:48.782878Z","steps":["trace[796593190] 'process raft request' (duration: 619.116508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:40:48.783019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.385935ms","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-23T04:40:48.783064Z","caller":"traceutil/trace.go:171","msg":"trace[821972490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1502; }","duration":"432.485889ms","start":"2026-06-23T04:40:48.350561Z","end":"2026-06-23T04:40:48.783047Z","steps":["trace[821972490] 'agreement among raft nodes before linearized reading' (duration: 432.325653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:40:48.783059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.843738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T04:40:48.7831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:40:48.350519Z","time spent":"432.573541ms","remote":"127.0.0.1:33098","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-23T04:40:48.783138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:40:48.163453Z","time spent":"619.580523ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:40:48.783148Z","caller":"traceutil/trace.go:171","msg":"trace[72280622] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1502; }","duration":"134.003603ms","start":"2026-06-23T04:40:48.649123Z","end":"2026-06-23T04:40:48.783127Z","steps":["trace[72280622] 'agreement among raft nodes before linearized reading' (duration: 133.815817ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:41:13.362813Z","caller":"traceutil/trace.go:171","msg":"trace[1996175311] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"123.0009ms","start":"2026-06-23T04:41:13.239786Z","end":"2026-06-23T04:41:13.362787Z","steps":["trace[1996175311] 'read index received' (duration: 122.721831ms)","trace[1996175311] 'applied index is now lower than readState.Index' (duration: 277.909µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:41:13.362894Z","caller":"traceutil/trace.go:171","msg":"trace[1907675188] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"128.80959ms","start":"2026-06-23T04:41:13.234046Z","end":"2026-06-23T04:41:13.362855Z","steps":["trace[1907675188] 'process raft request' (duration: 128.574983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:13.363104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.30361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-af3604d7-c3b8-4cc3-95ad-f560c3eb59f1\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-23T04:41:13.363156Z","caller":"traceutil/trace.go:171","msg":"trace[799816949] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-af3604d7-c3b8-4cc3-95ad-f560c3eb59f1; range_end:; response_count:1; response_revision:1918; }","duration":"123.438945ms","start":"2026-06-23T04:41:13.239705Z","end":"2026-06-23T04:41:13.363144Z","steps":["trace[799816949] 'agreement among raft nodes before linearized reading' (duration: 123.208067ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:41:13.893596Z","caller":"traceutil/trace.go:171","msg":"trace[162232571] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"364.870015ms","start":"2026-06-23T04:41:13.528691Z","end":"2026-06-23T04:41:13.893561Z","steps":["trace[162232571] 'process raft request' (duration: 362.642221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:13.894132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:41:13.528659Z","time spent":"365.0289ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:41:13.919743Z","caller":"traceutil/trace.go:171","msg":"trace[2084066080] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"138.836099ms","start":"2026-06-23T04:41:13.780874Z","end":"2026-06-23T04:41:13.91971Z","steps":["trace[2084066080] 'process raft request' (duration: 138.648333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:14.378153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.934807ms","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-23T04:41:14.378241Z","caller":"traceutil/trace.go:171","msg":"trace[1664953824] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1925; }","duration":"151.080042ms","start":"2026-06-23T04:41:14.227139Z","end":"2026-06-23T04:41:14.378219Z","steps":["trace[1664953824] 'range keys from in-memory index tree' (duration: 150.749671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:14.378332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.278047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-23T04:41:14.378445Z","caller":"traceutil/trace.go:171","msg":"trace[1788717527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1925; }","duration":"208.416501ms","start":"2026-06-23T04:41:14.169988Z","end":"2026-06-23T04:41:14.378404Z","steps":["trace[1788717527] 'range keys from in-memory index tree' (duration: 208.0772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:14.378328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.042304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-23T04:41:14.378472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.482247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-23T04:41:14.378664Z","caller":"traceutil/trace.go:171","msg":"trace[1319603199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1925; }","duration":"213.740035ms","start":"2026-06-23T04:41:14.164897Z","end":"2026-06-23T04:41:14.378637Z","steps":["trace[1319603199] 'range keys from in-memory index tree' (duration: 213.132386ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:41:14.378677Z","caller":"traceutil/trace.go:171","msg":"trace[108354032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1925; }","duration":"210.425347ms","start":"2026-06-23T04:41:14.168204Z","end":"2026-06-23T04:41:14.378629Z","steps":["trace[108354032] 'range keys from in-memory index tree' (duration: 209.812747ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:41:14.393499Z","caller":"traceutil/trace.go:171","msg":"trace[1340105139] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"146.307655ms","start":"2026-06-23T04:41:14.247147Z","end":"2026-06-23T04:41:14.393455Z","steps":["trace[1340105139] 'process raft request' (duration: 145.719436ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:41:15.283468Z","caller":"traceutil/trace.go:171","msg":"trace[1695454153] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2004; }","duration":"300.152532ms","start":"2026-06-23T04:41:14.983275Z","end":"2026-06-23T04:41:15.283427Z","steps":["trace[1695454153] 'read index received' (duration: 239.468522ms)","trace[1695454153] 'applied index is now lower than readState.Index' (duration: 60.68229ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:41:15.283632Z","caller":"traceutil/trace.go:171","msg":"trace[745438363] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"462.1095ms","start":"2026-06-23T04:41:14.821487Z","end":"2026-06-23T04:41:15.283596Z","steps":["trace[745438363] 'process raft request' (duration: 461.658906ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:41:15.283688Z","caller":"traceutil/trace.go:171","msg":"trace[703731739] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"151.429646ms","start":"2026-06-23T04:41:15.132245Z","end":"2026-06-23T04:41:15.283675Z","steps":["trace[703731739] 'process raft request' (duration: 151.120336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:15.283794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:41:14.821457Z","time spent":"462.249745ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T04:41:15.284101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.737249ms","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-23T04:41:15.284168Z","caller":"traceutil/trace.go:171","msg":"trace[589413204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1932; }","duration":"281.851963ms","start":"2026-06-23T04:41:15.002298Z","end":"2026-06-23T04:41:15.28415Z","steps":["trace[589413204] 'agreement among raft nodes before linearized reading' (duration: 281.662126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:15.284237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.950218ms","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-23T04:41:15.284294Z","caller":"traceutil/trace.go:171","msg":"trace[2096237590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1932; }","duration":"301.081893ms","start":"2026-06-23T04:41:14.983198Z","end":"2026-06-23T04:41:15.28428Z","steps":["trace[2096237590] 'agreement among raft nodes before linearized reading' (duration: 300.883526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:15.284338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:41:14.983166Z","time spent":"301.162705ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-23T04:41:25.644015Z","caller":"traceutil/trace.go:171","msg":"trace[1933236266] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"204.17646ms","start":"2026-06-23T04:41:25.439814Z","end":"2026-06-23T04:41:25.643991Z","steps":["trace[1933236266] 'process raft request' (duration: 204.044766ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:41:25.644131Z","caller":"traceutil/trace.go:171","msg":"trace[1385084861] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2093; }","duration":"199.233547ms","start":"2026-06-23T04:41:25.444878Z","end":"2026-06-23T04:41:25.644112Z","steps":["trace[1385084861] 'read index received' (duration: 199.223557ms)","trace[1385084861] 'applied index is now lower than readState.Index' (duration: 8.39µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:41:25.644293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.405762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T04:41:25.644335Z","caller":"traceutil/trace.go:171","msg":"trace[1497090624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2016; }","duration":"199.508606ms","start":"2026-06-23T04:41:25.444816Z","end":"2026-06-23T04:41:25.644325Z","steps":["trace[1497090624] 'agreement among raft nodes before linearized reading' (duration: 199.393572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:25.644876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.691289ms","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-23T04:41:25.644928Z","caller":"traceutil/trace.go:171","msg":"trace[1527288097] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2017; }","duration":"143.797592ms","start":"2026-06-23T04:41:25.501117Z","end":"2026-06-23T04:41:25.644914Z","steps":["trace[1527288097] 'agreement among raft nodes before linearized reading' (duration: 143.639857ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:41:25.644986Z","caller":"traceutil/trace.go:171","msg":"trace[181824649] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"134.751025ms","start":"2026-06-23T04:41:25.51021Z","end":"2026-06-23T04:41:25.644961Z","steps":["trace[181824649] 'process raft request' (duration: 134.488026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:41:56.381714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.404093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T04:41:56.381829Z","caller":"traceutil/trace.go:171","msg":"trace[1785281373] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"292.806479ms","start":"2026-06-23T04:41:56.089002Z","end":"2026-06-23T04:41:56.381809Z","steps":["trace[1785281373] 'process raft request' (duration: 69.21935ms)","trace[1785281373] 'compare' (duration: 223.021761ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:42:02.515232Z","caller":"traceutil/trace.go:171","msg":"trace[856920575] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"113.331871ms","start":"2026-06-23T04:42:02.401813Z","end":"2026-06-23T04:42:02.515145Z","steps":["trace[856920575] 'process raft request' (duration: 113.043822ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:42:02.996991Z","caller":"traceutil/trace.go:171","msg":"trace[1695815206] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"242.969566ms","start":"2026-06-23T04:42:02.753996Z","end":"2026-06-23T04:42:02.996965Z","steps":["trace[1695815206] 'process raft request' (duration: 194.792833ms)","trace[1695815206] 'compare' (duration: 48.039138ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:42:02.997361Z","caller":"traceutil/trace.go:171","msg":"trace[154143661] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"243.239155ms","start":"2026-06-23T04:42:02.754085Z","end":"2026-06-23T04:42:02.997324Z","steps":["trace[154143661] 'process raft request' (duration: 242.846922ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:42:02.99763Z","caller":"traceutil/trace.go:171","msg":"trace[1765765767] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"242.518491ms","start":"2026-06-23T04:42:02.755099Z","end":"2026-06-23T04:42:02.997617Z","steps":["trace[1765765767] 'process raft request' (duration: 242.124308ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:42:02.998086Z","caller":"traceutil/trace.go:171","msg":"trace[778434261] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"240.623178ms","start":"2026-06-23T04:42:02.757453Z","end":"2026-06-23T04:42:02.998076Z","steps":["trace[778434261] 'process raft request' (duration: 240.120051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:42:09.630183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.536062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T04:42:09.630282Z","caller":"traceutil/trace.go:171","msg":"trace[667149938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2272; }","duration":"184.699638ms","start":"2026-06-23T04:42:09.445561Z","end":"2026-06-23T04:42:09.630261Z","steps":["trace[667149938] 'range keys from in-memory index tree' (duration: 184.402168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:42:09.63043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.18175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-23T04:42:09.630566Z","caller":"traceutil/trace.go:171","msg":"trace[1636281465] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:2272; }","duration":"106.358316ms","start":"2026-06-23T04:42:09.524152Z","end":"2026-06-23T04:42:09.630511Z","steps":["trace[1636281465] 'range keys from in-memory index tree' (duration: 105.988524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:42:10.062868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:42:09.63176Z","time spent":"431.100943ms","remote":"127.0.0.1:58970","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-23T04:42:10.062859Z","caller":"traceutil/trace.go:171","msg":"trace[1174975223] linearizableReadLoop","detail":"{readStateIndex:2359; appliedIndex:2358; }","duration":"419.553909ms","start":"2026-06-23T04:42:09.643259Z","end":"2026-06-23T04:42:10.062813Z","steps":["trace[1174975223] 'read index received' (duration: 419.415924ms)","trace[1174975223] 'applied index is now lower than readState.Index' (duration: 135.985µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:42:10.06313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.836048ms","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-23T04:42:10.063199Z","caller":"traceutil/trace.go:171","msg":"trace[936711967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2272; }","duration":"419.969882ms","start":"2026-06-23T04:42:09.643215Z","end":"2026-06-23T04:42:10.063185Z","steps":["trace[936711967] 'agreement among raft nodes before linearized reading' (duration: 419.721984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:42:10.063245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:42:09.643188Z","time spent":"420.045975ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-23T04:42:10.063425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.506822ms","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-23T04:42:10.063551Z","caller":"traceutil/trace.go:171","msg":"trace[257806285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2272; }","duration":"289.691609ms","start":"2026-06-23T04:42:09.773805Z","end":"2026-06-23T04:42:10.063497Z","steps":["trace[257806285] 'agreement among raft nodes before linearized reading' (duration: 289.359878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:42:10.063486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.84335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T04:42:10.063654Z","caller":"traceutil/trace.go:171","msg":"trace[87672397] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2272; }","duration":"283.10894ms","start":"2026-06-23T04:42:09.780517Z","end":"2026-06-23T04:42:10.063626Z","steps":["trace[87672397] 'agreement among raft nodes before linearized reading' (duration: 282.8267ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:42:11.128193Z","caller":"traceutil/trace.go:171","msg":"trace[1182474690] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"587.531284ms","start":"2026-06-23T04:42:10.540631Z","end":"2026-06-23T04:42:11.128162Z","steps":["trace[1182474690] 'process raft request' (duration: 587.323537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:42:11.128405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:42:10.540602Z","time spent":"587.678689ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:42:11.134797Z","caller":"traceutil/trace.go:171","msg":"trace[696452837] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"441.627048ms","start":"2026-06-23T04:42:10.693148Z","end":"2026-06-23T04:42:11.134775Z","steps":["trace[696452837] 'process raft request' (duration: 441.556166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:42:11.134953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:42:10.69313Z","time spent":"441.735541ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:42:11.135093Z","caller":"traceutil/trace.go:171","msg":"trace[1280457009] linearizableReadLoop","detail":"{readStateIndex:2366; appliedIndex:2363; }","duration":"222.091917ms","start":"2026-06-23T04:42:10.912963Z","end":"2026-06-23T04:42:11.135055Z","steps":["trace[1280457009] 'read index received' (duration: 215.085184ms)","trace[1280457009] 'applied index is now lower than readState.Index' (duration: 7.003633ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:42:11.135073Z","caller":"traceutil/trace.go:171","msg":"trace[25580205] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"466.094214ms","start":"2026-06-23T04:42:10.668942Z","end":"2026-06-23T04:42:11.135036Z","steps":["trace[25580205] 'process raft request' (duration: 465.629128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:42:11.13532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.360566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-23T04:42:11.135314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:42:10.668916Z","time spent":"466.26968ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:42:11.135355Z","caller":"traceutil/trace.go:171","msg":"trace[1794422694] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2280; }","duration":"222.439379ms","start":"2026-06-23T04:42:10.912905Z","end":"2026-06-23T04:42:11.135344Z","steps":["trace[1794422694] 'agreement among raft nodes before linearized reading' (duration: 222.368347ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:42:25.389221Z","caller":"traceutil/trace.go:171","msg":"trace[337301868] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"131.820631ms","start":"2026-06-23T04:42:25.257372Z","end":"2026-06-23T04:42:25.389193Z","steps":["trace[337301868] 'process raft request' (duration: 117.510752ms)","trace[337301868] 'compare' (duration: 13.716619ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:42:25.38946Z","caller":"traceutil/trace.go:171","msg":"trace[294151961] transaction","detail":"{read_only:false; response_revision:2370; number_of_response:1; }","duration":"131.420808ms","start":"2026-06-23T04:42:25.257998Z","end":"2026-06-23T04:42:25.389419Z","steps":["trace[294151961] 'process raft request' (duration: 130.973883ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:42:25.389489Z","caller":"traceutil/trace.go:171","msg":"trace[1517917460] linearizableReadLoop","detail":"{readStateIndex:2459; appliedIndex:2456; }","duration":"121.519837ms","start":"2026-06-23T04:42:25.267929Z","end":"2026-06-23T04:42:25.389449Z","steps":["trace[1517917460] 'read index received' (duration: 9.141346ms)","trace[1517917460] 'applied index is now lower than readState.Index' (duration: 112.375371ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:42:25.391839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.890966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-06-23T04:42:25.391971Z","caller":"traceutil/trace.go:171","msg":"trace[279876254] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2370; }","duration":"124.066652ms","start":"2026-06-23T04:42:25.267889Z","end":"2026-06-23T04:42:25.391956Z","steps":["trace[279876254] 'agreement among raft nodes before linearized reading' (duration: 121.949691ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:42:56.353156Z","caller":"traceutil/trace.go:171","msg":"trace[674099924] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"187.258704ms","start":"2026-06-23T04:42:56.165871Z","end":"2026-06-23T04:42:56.353129Z","steps":["trace[674099924] 'process raft request' (duration: 107.322853ms)","trace[674099924] 'compare' (duration: 79.720873ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:43:01.510113Z","caller":"traceutil/trace.go:171","msg":"trace[1949057845] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"109.424226ms","start":"2026-06-23T04:43:01.40065Z","end":"2026-06-23T04:43:01.510075Z","steps":["trace[1949057845] 'process raft request' (duration: 61.719199ms)","trace[1949057845] 'compare' (duration: 47.448698ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:43:02.284155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.243922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-23T04:43:02.284264Z","caller":"traceutil/trace.go:171","msg":"trace[220438456] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2730; }","duration":"467.731279ms","start":"2026-06-23T04:43:01.816512Z","end":"2026-06-23T04:43:02.284243Z","steps":["trace[220438456] 'range keys from in-memory index tree' (duration: 466.454136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:43:02.284309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:43:01.816485Z","time spent":"467.811112ms","remote":"127.0.0.1:33040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-06-23T04:43:37.073387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.821111ms","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-23T04:43:37.073509Z","caller":"traceutil/trace.go:171","msg":"trace[1774920430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2937; }","duration":"217.030598ms","start":"2026-06-23T04:43:36.856456Z","end":"2026-06-23T04:43:37.073486Z","steps":["trace[1774920430] 'range keys from in-memory index tree' (duration: 216.46961ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:43:37.221747Z","caller":"traceutil/trace.go:171","msg":"trace[535905081] transaction","detail":"{read_only:false; response_revision:2938; number_of_response:1; }","duration":"142.797405ms","start":"2026-06-23T04:43:37.078922Z","end":"2026-06-23T04:43:37.221719Z","steps":["trace[535905081] 'process raft request' (duration: 142.622649ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:43:37.703712Z","caller":"traceutil/trace.go:171","msg":"trace[1239370538] transaction","detail":"{read_only:false; response_revision:2939; number_of_response:1; }","duration":"174.873672ms","start":"2026-06-23T04:43:37.528803Z","end":"2026-06-23T04:43:37.703677Z","steps":["trace[1239370538] 'process raft request' (duration: 174.649504ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:43:37.933579Z","caller":"traceutil/trace.go:171","msg":"trace[1224303259] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"274.204474ms","start":"2026-06-23T04:43:37.659321Z","end":"2026-06-23T04:43:37.933525Z","steps":["trace[1224303259] 'process raft request' (duration: 274.095741ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:43:37.933652Z","caller":"traceutil/trace.go:171","msg":"trace[620742873] linearizableReadLoop","detail":"{readStateIndex:3053; appliedIndex:3051; }","duration":"224.15365ms","start":"2026-06-23T04:43:37.709445Z","end":"2026-06-23T04:43:37.933598Z","steps":["trace[620742873] 'read index received' (duration: 169.951845ms)","trace[620742873] 'applied index is now lower than readState.Index' (duration: 54.186614ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:43:37.933579Z","caller":"traceutil/trace.go:171","msg":"trace[1461807183] transaction","detail":"{read_only:false; response_revision:2940; number_of_response:1; }","duration":"274.472233ms","start":"2026-06-23T04:43:37.659051Z","end":"2026-06-23T04:43:37.933523Z","steps":["trace[1461807183] 'process raft request' (duration: 220.411103ms)","trace[1461807183] 'compare' (duration: 53.819882ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:43:37.933964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.505642ms","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-23T04:43:37.933961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.459965ms","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-23T04:43:37.934003Z","caller":"traceutil/trace.go:171","msg":"trace[1712671986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2943; }","duration":"224.609235ms","start":"2026-06-23T04:43:37.709385Z","end":"2026-06-23T04:43:37.933994Z","steps":["trace[1712671986] 'agreement among raft nodes before linearized reading' (duration: 224.421029ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:43:37.934044Z","caller":"traceutil/trace.go:171","msg":"trace[963875644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2943; }","duration":"171.59988ms","start":"2026-06-23T04:43:37.762423Z","end":"2026-06-23T04:43:37.934023Z","steps":["trace[963875644] 'agreement among raft nodes before linearized reading' (duration: 171.417364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:43:37.934047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.534604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-23T04:43:37.934082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.200476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-23T04:43:37.934187Z","caller":"traceutil/trace.go:171","msg":"trace[1970783217] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2943; }","duration":"119.32068ms","start":"2026-06-23T04:43:37.81484Z","end":"2026-06-23T04:43:37.93416Z","steps":["trace[1970783217] 'agreement among raft nodes before linearized reading' (duration: 119.01342ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:43:37.934117Z","caller":"traceutil/trace.go:171","msg":"trace[2128035293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2943; }","duration":"170.639178ms","start":"2026-06-23T04:43:37.76346Z","end":"2026-06-23T04:43:37.934099Z","steps":["trace[2128035293] 'agreement among raft nodes before linearized reading' (duration: 170.41681ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:46:30.575791Z","caller":"traceutil/trace.go:171","msg":"trace[1101690169] transaction","detail":"{read_only:false; response_revision:4054; number_of_response:1; }","duration":"101.182973ms","start":"2026-06-23T04:46:30.474583Z","end":"2026-06-23T04:46:30.575766Z","steps":["trace[1101690169] 'process raft request' (duration: 101.044169ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:46:30.805334Z","caller":"traceutil/trace.go:171","msg":"trace[197409555] transaction","detail":"{read_only:false; response_revision:4055; number_of_response:1; }","duration":"166.154749ms","start":"2026-06-23T04:46:30.639144Z","end":"2026-06-23T04:46:30.805298Z","steps":["trace[197409555] 'process raft request' (duration: 146.50746ms)","trace[197409555] 'compare' (duration: 19.3056ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:46:30.829099Z","caller":"traceutil/trace.go:171","msg":"trace[1379565021] transaction","detail":"{read_only:false; response_revision:4056; number_of_response:1; }","duration":"189.198447ms","start":"2026-06-23T04:46:30.639883Z","end":"2026-06-23T04:46:30.829081Z","steps":["trace[1379565021] 'process raft request' (duration: 189.046433ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:46:30.829301Z","caller":"traceutil/trace.go:171","msg":"trace[566182534] transaction","detail":"{read_only:false; response_revision:4057; number_of_response:1; }","duration":"149.671111ms","start":"2026-06-23T04:46:30.679619Z","end":"2026-06-23T04:46:30.82929Z","steps":["trace[566182534] 'process raft request' (duration: 149.405665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:47:47.647356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.752488ms","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-23T04:47:47.647447Z","caller":"traceutil/trace.go:171","msg":"trace[7238560] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4944; }","duration":"108.900153ms","start":"2026-06-23T04:47:47.538528Z","end":"2026-06-23T04:47:47.647428Z","steps":["trace[7238560] 'range keys from in-memory index tree' (duration: 108.616815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:47:47.649373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.197721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:49 size:1957109"} {"level":"info","ts":"2026-06-23T04:47:47.649477Z","caller":"traceutil/trace.go:171","msg":"trace[2018919037] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:49; response_revision:4944; }","duration":"171.339275ms","start":"2026-06-23T04:47:47.478116Z","end":"2026-06-23T04:47:47.649455Z","steps":["trace[2018919037] 'range keys from in-memory index tree' (duration: 169.306199ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:47:52.984039Z","caller":"traceutil/trace.go:171","msg":"trace[1070772114] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"147.146667ms","start":"2026-06-23T04:47:52.836865Z","end":"2026-06-23T04:47:52.984011Z","steps":["trace[1070772114] 'process raft request' (duration: 147.003913ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:47:52.984246Z","caller":"traceutil/trace.go:171","msg":"trace[1448339838] linearizableReadLoop","detail":"{readStateIndex:5279; appliedIndex:5279; }","duration":"130.320678ms","start":"2026-06-23T04:47:52.853843Z","end":"2026-06-23T04:47:52.984163Z","steps":["trace[1448339838] 'read index received' (duration: 130.308088ms)","trace[1448339838] 'applied index is now lower than readState.Index' (duration: 10.13µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:47:52.984563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.637797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T04:47:52.984614Z","caller":"traceutil/trace.go:171","msg":"trace[1201524599] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5084; }","duration":"130.770211ms","start":"2026-06-23T04:47:52.853832Z","end":"2026-06-23T04:47:52.984602Z","steps":["trace[1201524599] 'agreement among raft nodes before linearized reading' (duration: 130.595036ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:47:53.755115Z","caller":"traceutil/trace.go:171","msg":"trace[2107566309] linearizableReadLoop","detail":"{readStateIndex:5302; appliedIndex:5301; }","duration":"131.779542ms","start":"2026-06-23T04:47:53.623308Z","end":"2026-06-23T04:47:53.755088Z","steps":["trace[2107566309] 'read index received' (duration: 131.589017ms)","trace[2107566309] 'applied index is now lower than readState.Index' (duration: 189.075µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:47:53.755234Z","caller":"traceutil/trace.go:171","msg":"trace[2064295647] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"199.724017ms","start":"2026-06-23T04:47:53.55548Z","end":"2026-06-23T04:47:53.755204Z","steps":["trace[2064295647] 'process raft request' (duration: 199.407448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:47:53.755351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.975368ms","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-23T04:47:53.755417Z","caller":"traceutil/trace.go:171","msg":"trace[102613817] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5107; }","duration":"132.154653ms","start":"2026-06-23T04:47:53.623243Z","end":"2026-06-23T04:47:53.755397Z","steps":["trace[102613817] 'agreement among raft nodes before linearized reading' (duration: 131.993019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:47:56.960622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.25195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T04:47:56.960724Z","caller":"traceutil/trace.go:171","msg":"trace[1251027395] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5227; }","duration":"168.410764ms","start":"2026-06-23T04:47:56.792296Z","end":"2026-06-23T04:47:56.960707Z","steps":["trace[1251027395] 'range keys from in-memory index tree' (duration: 168.163496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:47:56.961197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.393058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9711ad9-f080-463e-a755-436570fdd037\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-06-23T04:47:56.961307Z","caller":"traceutil/trace.go:171","msg":"trace[737436352] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9711ad9-f080-463e-a755-436570fdd037; range_end:; response_count:1; response_revision:5227; }","duration":"103.526162ms","start":"2026-06-23T04:47:56.857751Z","end":"2026-06-23T04:47:56.961277Z","steps":["trace[737436352] 'range keys from in-memory index tree' (duration: 103.148261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:47:56.961219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.55345ms","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-23T04:47:56.961769Z","caller":"traceutil/trace.go:171","msg":"trace[1951679915] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5227; }","duration":"245.25749ms","start":"2026-06-23T04:47:56.716497Z","end":"2026-06-23T04:47:56.961755Z","steps":["trace[1951679915] 'range keys from in-memory index tree' (duration: 244.290453ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:47:57.317062Z","caller":"traceutil/trace.go:171","msg":"trace[1942984861] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"315.21733ms","start":"2026-06-23T04:47:57.001814Z","end":"2026-06-23T04:47:57.317031Z","steps":["trace[1942984861] 'process raft request' (duration: 289.407839ms)","trace[1942984861] 'compare' (duration: 25.704648ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:47:57.317288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:47:57.001801Z","time spent":"315.348413ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:47:57.385038Z","caller":"traceutil/trace.go:171","msg":"trace[249514553] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5229; }","duration":"370.157616ms","start":"2026-06-23T04:47:57.014842Z","end":"2026-06-23T04:47:57.385Z","steps":["trace[249514553] 'process raft request' (duration: 369.887518ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:47:57.385077Z","caller":"traceutil/trace.go:171","msg":"trace[1428799279] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"181.38632ms","start":"2026-06-23T04:47:57.203653Z","end":"2026-06-23T04:47:57.38504Z","steps":["trace[1428799279] 'process raft request' (duration: 181.312998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:47:57.385227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:47:57.014826Z","time spent":"370.308299ms","remote":"127.0.0.1:59048","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T04:47:57.385398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.588105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:448738"} {"level":"info","ts":"2026-06-23T04:47:57.385455Z","caller":"traceutil/trace.go:171","msg":"trace[1689294726] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5231; }","duration":"352.698868ms","start":"2026-06-23T04:47:57.032746Z","end":"2026-06-23T04:47:57.385445Z","steps":["trace[1689294726] 'agreement among raft nodes before linearized reading' (duration: 352.365469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:47:57.385489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:47:57.032736Z","time spent":"352.745869ms","remote":"127.0.0.1:58344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":448761,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-06-23T04:47:57.385045Z","caller":"traceutil/trace.go:171","msg":"trace[2043571028] linearizableReadLoop","detail":"{readStateIndex:5428; appliedIndex:5426; }","duration":"352.225155ms","start":"2026-06-23T04:47:57.032784Z","end":"2026-06-23T04:47:57.385009Z","steps":["trace[2043571028] 'read index received' (duration: 258.535796ms)","trace[2043571028] 'applied index is now lower than readState.Index' (duration: 93.687869ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:47:57.385883Z","caller":"traceutil/trace.go:171","msg":"trace[889988629] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"182.633154ms","start":"2026-06-23T04:47:57.203208Z","end":"2026-06-23T04:47:57.385841Z","steps":["trace[889988629] 'process raft request' (duration: 181.654227ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:47:57.760039Z","caller":"traceutil/trace.go:171","msg":"trace[2142566920] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"169.821427ms","start":"2026-06-23T04:47:57.590181Z","end":"2026-06-23T04:47:57.760003Z","steps":["trace[2142566920] 'process raft request' (duration: 86.748875ms)","trace[2142566920] 'compare' (duration: 82.910157ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:47:57.76802Z","caller":"traceutil/trace.go:171","msg":"trace[1680934713] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"166.190985ms","start":"2026-06-23T04:47:57.601806Z","end":"2026-06-23T04:47:57.767997Z","steps":["trace[1680934713] 'process raft request' (duration: 166.082762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:47:59.75326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.290774ms","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-23T04:47:59.753329Z","caller":"traceutil/trace.go:171","msg":"trace[2007544158] 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:5285; }","duration":"141.405847ms","start":"2026-06-23T04:47:59.611905Z","end":"2026-06-23T04:47:59.753311Z","steps":["trace[2007544158] 'range keys from in-memory index tree' (duration: 141.15688ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:48:10.356654Z","caller":"traceutil/trace.go:171","msg":"trace[1928210206] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"102.114139ms","start":"2026-06-23T04:48:10.254519Z","end":"2026-06-23T04:48:10.356633Z","steps":["trace[1928210206] 'process raft request' (duration: 101.958855ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:48:12.190466Z","caller":"traceutil/trace.go:171","msg":"trace[409325714] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"102.36122ms","start":"2026-06-23T04:48:12.088077Z","end":"2026-06-23T04:48:12.190438Z","steps":["trace[409325714] 'process raft request' (duration: 30.859891ms)","trace[409325714] 'compare' (duration: 71.235051ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:48:13.195136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103938280488513,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T04:48:13.328427Z","caller":"traceutil/trace.go:171","msg":"trace[832027836] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"667.039167ms","start":"2026-06-23T04:48:12.661361Z","end":"2026-06-23T04:48:13.328401Z","steps":["trace[832027836] 'process raft request' (duration: 666.924844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:48:13.328615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:48:12.661341Z","time spent":"667.215153ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7956,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:48:13.50467Z","caller":"traceutil/trace.go:171","msg":"trace[2036298213] linearizableReadLoop","detail":"{readStateIndex:5719; appliedIndex:5718; }","duration":"810.663005ms","start":"2026-06-23T04:48:12.693987Z","end":"2026-06-23T04:48:13.50465Z","steps":["trace[2036298213] 'read index received' (duration: 634.486059ms)","trace[2036298213] 'applied index is now lower than readState.Index' (duration: 176.176276ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:48:13.504777Z","caller":"traceutil/trace.go:171","msg":"trace[1245106644] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"668.350498ms","start":"2026-06-23T04:48:12.836418Z","end":"2026-06-23T04:48:13.504768Z","steps":["trace[1245106644] 'process raft request' (duration: 668.125871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:48:13.504853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:48:12.836394Z","time spent":"668.40015ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T04:48:13.504874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.888232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T04:48:13.504912Z","caller":"traceutil/trace.go:171","msg":"trace[626102734] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:5511; }","duration":"810.964155ms","start":"2026-06-23T04:48:12.693937Z","end":"2026-06-23T04:48:13.504901Z","steps":["trace[626102734] 'agreement among raft nodes before linearized reading' (duration: 810.892423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:48:13.504934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:48:12.693917Z","time spent":"811.011485ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-23T04:48:13.512267Z","caller":"traceutil/trace.go:171","msg":"trace[431298131] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"176.751463ms","start":"2026-06-23T04:48:13.335483Z","end":"2026-06-23T04:48:13.512234Z","steps":["trace[431298131] 'process raft request' (duration: 175.876938ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:48:13.512269Z","caller":"traceutil/trace.go:171","msg":"trace[1126209663] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"177.446583ms","start":"2026-06-23T04:48:13.334764Z","end":"2026-06-23T04:48:13.51221Z","steps":["trace[1126209663] 'process raft request' (duration: 176.571108ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:48:13.512396Z","caller":"traceutil/trace.go:171","msg":"trace[1001349273] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"178.923424ms","start":"2026-06-23T04:48:13.333439Z","end":"2026-06-23T04:48:13.512362Z","steps":["trace[1001349273] 'process raft request' (duration: 177.756971ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:48:13.512551Z","caller":"traceutil/trace.go:171","msg":"trace[1883796472] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"178.847302ms","start":"2026-06-23T04:48:13.333623Z","end":"2026-06-23T04:48:13.51247Z","steps":["trace[1883796472] 'process raft request' (duration: 177.673389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:48:13.512636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.984345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-23T04:48:13.512643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.416557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-849d7d87cd-p6sqp\" ","response":"range_response_count:1 size:11847"} {"level":"info","ts":"2026-06-23T04:48:13.512697Z","caller":"traceutil/trace.go:171","msg":"trace[1398755829] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-849d7d87cd-p6sqp; range_end:; response_count:1; response_revision:5518; }","duration":"180.510059ms","start":"2026-06-23T04:48:13.332171Z","end":"2026-06-23T04:48:13.512681Z","steps":["trace[1398755829] 'agreement among raft nodes before linearized reading' (duration: 180.347915ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:48:13.512692Z","caller":"traceutil/trace.go:171","msg":"trace[2142334666] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5518; }","duration":"386.078017ms","start":"2026-06-23T04:48:13.126595Z","end":"2026-06-23T04:48:13.512673Z","steps":["trace[2142334666] 'agreement among raft nodes before linearized reading' (duration: 385.987595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:48:13.512578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.676449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T04:48:13.512736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:48:13.126576Z","time spent":"386.149509ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":8,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-06-23T04:48:13.512819Z","caller":"traceutil/trace.go:171","msg":"trace[1450448925] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5518; }","duration":"320.960377ms","start":"2026-06-23T04:48:13.191813Z","end":"2026-06-23T04:48:13.512773Z","steps":["trace[1450448925] 'agreement among raft nodes before linearized reading' (duration: 320.672889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:48:13.512928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:48:13.1918Z","time spent":"321.08721ms","remote":"127.0.0.1:44766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-06-23T04:48:13.512955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.658746ms","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-23T04:48:13.51299Z","caller":"traceutil/trace.go:171","msg":"trace[134709995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5518; }","duration":"419.734458ms","start":"2026-06-23T04:48:13.093244Z","end":"2026-06-23T04:48:13.512979Z","steps":["trace[134709995] 'agreement among raft nodes before linearized reading' (duration: 418.224655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:48:13.513012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:48:13.093226Z","time spent":"419.780879ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-23T04:48:32.678521Z","caller":"traceutil/trace.go:171","msg":"trace[1456520015] transaction","detail":"{read_only:false; response_revision:5808; number_of_response:1; }","duration":"195.55904ms","start":"2026-06-23T04:48:32.482928Z","end":"2026-06-23T04:48:32.678487Z","steps":["trace[1456520015] 'process raft request' (duration: 195.387375ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:48:46.26256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2992} {"level":"info","ts":"2026-06-23T04:48:46.371319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2992,"took":"107.019033ms","hash":279551243,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":32600064,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-23T04:48:46.371382Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":279551243,"revision":2992,"compact-revision":-1} {"level":"info","ts":"2026-06-23T04:49:25.484805Z","caller":"traceutil/trace.go:171","msg":"trace[1215436023] transaction","detail":"{read_only:false; response_revision:6242; number_of_response:1; }","duration":"111.394415ms","start":"2026-06-23T04:49:25.373385Z","end":"2026-06-23T04:49:25.484779Z","steps":["trace[1215436023] 'process raft request' (duration: 111.22807ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:53:46.270924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5912} {"level":"info","ts":"2026-06-23T04:53:46.368654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5912,"took":"95.798002ms","hash":4073129456,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":26619904,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-23T04:53:46.368717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4073129456,"revision":5912,"compact-revision":2992} {"level":"info","ts":"2026-06-23T04:54:59.53508Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002310.wal"} {"level":"info","ts":"2026-06-23T04:55:03.516522Z","caller":"traceutil/trace.go:171","msg":"trace[1567464805] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"237.989039ms","start":"2026-06-23T04:55:03.278498Z","end":"2026-06-23T04:55:03.516487Z","steps":["trace[1567464805] 'process raft request' (duration: 237.750121ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:03.827796Z","caller":"traceutil/trace.go:171","msg":"trace[131527646] linearizableReadLoop","detail":"{readStateIndex:9002; appliedIndex:8999; }","duration":"313.606707ms","start":"2026-06-23T04:55:03.514159Z","end":"2026-06-23T04:55:03.827766Z","steps":["trace[131527646] 'read index received' (duration: 2.033475ms)","trace[131527646] 'applied index is now lower than readState.Index' (duration: 311.571892ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:55:03.827911Z","caller":"traceutil/trace.go:171","msg":"trace[1114041359] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"469.633176ms","start":"2026-06-23T04:55:03.358235Z","end":"2026-06-23T04:55:03.827868Z","steps":["trace[1114041359] 'process raft request' (duration: 469.478481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:03.827978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.786404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T04:55:03.828023Z","caller":"traceutil/trace.go:171","msg":"trace[133704810] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8690; }","duration":"313.898507ms","start":"2026-06-23T04:55:03.514114Z","end":"2026-06-23T04:55:03.828012Z","steps":["trace[133704810] 'agreement among raft nodes before linearized reading' (duration: 313.790373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:03.828064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:55:03.514095Z","time spent":"313.958228ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-06-23T04:55:03.828106Z","caller":"traceutil/trace.go:171","msg":"trace[359317857] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"514.141729ms","start":"2026-06-23T04:55:03.313935Z","end":"2026-06-23T04:55:03.828076Z","steps":["trace[359317857] 'process raft request' (duration: 513.55282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:03.828115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:55:03.358206Z","time spent":"469.798832ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T04:55:03.828242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:55:03.313909Z","time spent":"514.270903ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T04:55:03.828647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.990136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-06-23T04:55:03.828722Z","caller":"traceutil/trace.go:171","msg":"trace[1745557295] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8691; }","duration":"180.096939ms","start":"2026-06-23T04:55:03.648608Z","end":"2026-06-23T04:55:03.828704Z","steps":["trace[1745557295] 'agreement among raft nodes before linearized reading' (duration: 179.863992ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:03.828729Z","caller":"traceutil/trace.go:171","msg":"trace[228903715] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"289.135875ms","start":"2026-06-23T04:55:03.539386Z","end":"2026-06-23T04:55:03.828522Z","steps":["trace[228903715] 'process raft request' (duration: 288.955899ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:14.922679Z","caller":"traceutil/trace.go:171","msg":"trace[160847079] linearizableReadLoop","detail":"{readStateIndex:9063; appliedIndex:9062; }","duration":"107.349666ms","start":"2026-06-23T04:55:14.815298Z","end":"2026-06-23T04:55:14.922648Z","steps":["trace[160847079] 'read index received' (duration: 88.490082ms)","trace[160847079] 'applied index is now lower than readState.Index' (duration: 18.857564ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:55:14.922874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.562493ms","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-23T04:55:14.922929Z","caller":"traceutil/trace.go:171","msg":"trace[1829875743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8748; }","duration":"107.690788ms","start":"2026-06-23T04:55:14.815226Z","end":"2026-06-23T04:55:14.922917Z","steps":["trace[1829875743] 'agreement among raft nodes before linearized reading' (duration: 107.501962ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:15.948418Z","caller":"traceutil/trace.go:171","msg":"trace[1893593708] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"389.437219ms","start":"2026-06-23T04:55:15.558912Z","end":"2026-06-23T04:55:15.948349Z","steps":["trace[1893593708] 'process raft request' (duration: 388.880501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:15.94887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:55:15.558877Z","time spent":"389.77847ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T04:55:17.022971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.255612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T04:55:17.023033Z","caller":"traceutil/trace.go:171","msg":"trace[655934882] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8758; }","duration":"359.392127ms","start":"2026-06-23T04:55:16.663627Z","end":"2026-06-23T04:55:17.023019Z","steps":["trace[655934882] 'count revisions from in-memory index tree' (duration: 359.117738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:17.023061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:55:16.663595Z","time spent":"359.459509ms","remote":"127.0.0.1:33228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-23T04:55:17.023021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.719474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7vvq5\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-06-23T04:55:17.023126Z","caller":"traceutil/trace.go:171","msg":"trace[1945051730] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7vvq5; range_end:; response_count:1; response_revision:8758; }","duration":"446.882298ms","start":"2026-06-23T04:55:16.576222Z","end":"2026-06-23T04:55:17.023104Z","steps":["trace[1945051730] 'range keys from in-memory index tree' (duration: 446.489116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:17.023164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:55:16.576205Z","time spent":"446.951771ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6464,"request content":"key:\"/registry/pods/openstack/keepalived-7vvq5\" "} {"level":"info","ts":"2026-06-23T04:55:18.279944Z","caller":"traceutil/trace.go:171","msg":"trace[274140783] linearizableReadLoop","detail":"{readStateIndex:9081; appliedIndex:9080; }","duration":"158.818847ms","start":"2026-06-23T04:55:18.121098Z","end":"2026-06-23T04:55:18.279917Z","steps":["trace[274140783] 'read index received' (duration: 158.574759ms)","trace[274140783] 'applied index is now lower than readState.Index' (duration: 243.048µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:55:18.280082Z","caller":"traceutil/trace.go:171","msg":"trace[1122237139] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"160.703897ms","start":"2026-06-23T04:55:18.119306Z","end":"2026-06-23T04:55:18.28001Z","steps":["trace[1122237139] 'process raft request' (duration: 160.444239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:18.280257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.122997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-06-23T04:55:18.280257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.273959ms","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-23T04:55:18.280313Z","caller":"traceutil/trace.go:171","msg":"trace[2090615419] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8766; }","duration":"159.202939ms","start":"2026-06-23T04:55:18.121095Z","end":"2026-06-23T04:55:18.280298Z","steps":["trace[2090615419] 'agreement among raft nodes before linearized reading' (duration: 158.990922ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:18.280347Z","caller":"traceutil/trace.go:171","msg":"trace[1673463533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8766; }","duration":"153.376852ms","start":"2026-06-23T04:55:18.126947Z","end":"2026-06-23T04:55:18.280324Z","steps":["trace[1673463533] 'agreement among raft nodes before linearized reading' (duration: 153.187786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:18.280394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.747116ms","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-23T04:55:18.280467Z","caller":"traceutil/trace.go:171","msg":"trace[223220558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8766; }","duration":"149.848669ms","start":"2026-06-23T04:55:18.1306Z","end":"2026-06-23T04:55:18.280448Z","steps":["trace[223220558] 'agreement among raft nodes before linearized reading' (duration: 149.608261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:44.705343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.726715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T04:55:44.7055Z","caller":"traceutil/trace.go:171","msg":"trace[2088376350] linearizableReadLoop","detail":"{readStateIndex:9283; appliedIndex:9282; }","duration":"104.660681ms","start":"2026-06-23T04:55:44.600815Z","end":"2026-06-23T04:55:44.705475Z","steps":["trace[2088376350] 'read index received' (duration: 21.831µs)","trace[2088376350] 'applied index is now lower than readState.Index' (duration: 104.63678ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:55:44.705558Z","caller":"traceutil/trace.go:171","msg":"trace[39635036] transaction","detail":"{read_only:false; response_revision:8960; number_of_response:1; }","duration":"228.594381ms","start":"2026-06-23T04:55:44.476906Z","end":"2026-06-23T04:55:44.7055Z","steps":["trace[39635036] 'process raft request' (duration: 51.583857ms)","trace[39635036] 'compare' (duration: 176.58688ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:55:44.705806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.987781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6cntl\" ","response":"range_response_count:1 size:4454"} {"level":"info","ts":"2026-06-23T04:55:44.705857Z","caller":"traceutil/trace.go:171","msg":"trace[1000907912] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6cntl; range_end:; response_count:1; response_revision:8960; }","duration":"105.068084ms","start":"2026-06-23T04:55:44.600771Z","end":"2026-06-23T04:55:44.705839Z","steps":["trace[1000907912] 'agreement among raft nodes before linearized reading' (duration: 104.890298ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:49.031519Z","caller":"traceutil/trace.go:171","msg":"trace[107262655] transaction","detail":"{read_only:false; response_revision:8983; number_of_response:1; }","duration":"298.54218ms","start":"2026-06-23T04:55:48.732939Z","end":"2026-06-23T04:55:49.031481Z","steps":["trace[107262655] 'process raft request' (duration: 298.057174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:49.287723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.963353ms","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-23T04:55:49.287817Z","caller":"traceutil/trace.go:171","msg":"trace[1279649219] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8983; }","duration":"143.099608ms","start":"2026-06-23T04:55:49.144697Z","end":"2026-06-23T04:55:49.287797Z","steps":["trace[1279649219] 'range keys from in-memory index tree' (duration: 142.886461ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:53.444578Z","caller":"traceutil/trace.go:171","msg":"trace[1815712086] linearizableReadLoop","detail":"{readStateIndex:9329; appliedIndex:9328; }","duration":"100.387647ms","start":"2026-06-23T04:55:53.344138Z","end":"2026-06-23T04:55:53.444526Z","steps":["trace[1815712086] 'read index received' (duration: 57.25986ms)","trace[1815712086] 'applied index is now lower than readState.Index' (duration: 43.127017ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:55:53.444684Z","caller":"traceutil/trace.go:171","msg":"trace[775150554] transaction","detail":"{read_only:false; response_revision:9004; number_of_response:1; }","duration":"104.806108ms","start":"2026-06-23T04:55:53.339867Z","end":"2026-06-23T04:55:53.444673Z","steps":["trace[775150554] 'process raft request' (duration: 61.443005ms)","trace[775150554] 'compare' (duration: 43.052343ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:55:53.444986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.993699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7vvq5\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-06-23T04:55:53.44509Z","caller":"traceutil/trace.go:171","msg":"trace[1280599999] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7vvq5; range_end:; response_count:1; response_revision:9004; }","duration":"126.144144ms","start":"2026-06-23T04:55:53.318922Z","end":"2026-06-23T04:55:53.445066Z","steps":["trace[1280599999] 'agreement among raft nodes before linearized reading' (duration: 125.854245ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:53.480949Z","caller":"traceutil/trace.go:171","msg":"trace[442487558] transaction","detail":"{read_only:false; response_revision:9005; number_of_response:1; }","duration":"131.380053ms","start":"2026-06-23T04:55:53.349536Z","end":"2026-06-23T04:55:53.480916Z","steps":["trace[442487558] 'process raft request' (duration: 131.222328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:53.48129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.596893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ceph.18bb9cdb4e0813e6\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-06-23T04:55:53.481384Z","caller":"traceutil/trace.go:171","msg":"trace[1694025238] range","detail":"{range_begin:/registry/events/openstack/ceph.18bb9cdb4e0813e6; range_end:; response_count:1; response_revision:9005; }","duration":"128.758039ms","start":"2026-06-23T04:55:53.352606Z","end":"2026-06-23T04:55:53.481364Z","steps":["trace[1694025238] 'agreement among raft nodes before linearized reading' (duration: 128.475199ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:53.856857Z","caller":"traceutil/trace.go:171","msg":"trace[1625594175] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"308.802515ms","start":"2026-06-23T04:55:53.548033Z","end":"2026-06-23T04:55:53.856836Z","steps":["trace[1625594175] 'process raft request' (duration: 308.675301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:55:53.856988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:55:53.548007Z","time spent":"308.915228ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T04:55:54.250975Z","caller":"traceutil/trace.go:171","msg":"trace[1315920496] transaction","detail":"{read_only:false; response_revision:9009; number_of_response:1; }","duration":"134.021018ms","start":"2026-06-23T04:55:54.116917Z","end":"2026-06-23T04:55:54.250938Z","steps":["trace[1315920496] 'process raft request' (duration: 133.797301ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:54.257142Z","caller":"traceutil/trace.go:171","msg":"trace[161346878] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"138.092119ms","start":"2026-06-23T04:55:54.119024Z","end":"2026-06-23T04:55:54.257116Z","steps":["trace[161346878] 'process raft request' (duration: 137.911843ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:54.25737Z","caller":"traceutil/trace.go:171","msg":"trace[379373332] transaction","detail":"{read_only:false; response_revision:9012; number_of_response:1; }","duration":"136.043383ms","start":"2026-06-23T04:55:54.12131Z","end":"2026-06-23T04:55:54.257354Z","steps":["trace[379373332] 'process raft request' (duration: 135.967521ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:55:54.257441Z","caller":"traceutil/trace.go:171","msg":"trace[1937901756] transaction","detail":"{read_only:false; response_revision:9011; number_of_response:1; }","duration":"137.765268ms","start":"2026-06-23T04:55:54.119638Z","end":"2026-06-23T04:55:54.257403Z","steps":["trace[1937901756] 'process raft request' (duration: 137.438277ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:56:03.334452Z","caller":"traceutil/trace.go:171","msg":"trace[50841516] transaction","detail":"{read_only:false; response_revision:9101; number_of_response:1; }","duration":"140.459638ms","start":"2026-06-23T04:56:03.193966Z","end":"2026-06-23T04:56:03.334425Z","steps":["trace[50841516] 'process raft request' (duration: 140.312273ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:56:03.526426Z","caller":"traceutil/trace.go:171","msg":"trace[1134575399] transaction","detail":"{read_only:false; response_revision:9102; number_of_response:1; }","duration":"159.659896ms","start":"2026-06-23T04:56:03.366742Z","end":"2026-06-23T04:56:03.526402Z","steps":["trace[1134575399] 'process raft request' (duration: 142.959959ms)","trace[1134575399] 'compare' (duration: 16.48785ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:56:04.433339Z","caller":"traceutil/trace.go:171","msg":"trace[124460375] transaction","detail":"{read_only:false; response_revision:9105; number_of_response:1; }","duration":"207.046042ms","start":"2026-06-23T04:56:04.226263Z","end":"2026-06-23T04:56:04.433309Z","steps":["trace[124460375] 'process raft request' (duration: 206.845905ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:56:04.448153Z","caller":"traceutil/trace.go:171","msg":"trace[1624898633] linearizableReadLoop","detail":"{readStateIndex:9439; appliedIndex:9434; }","duration":"112.492809ms","start":"2026-06-23T04:56:04.335631Z","end":"2026-06-23T04:56:04.448124Z","steps":["trace[1624898633] 'read index received' (duration: 97.577379ms)","trace[1624898633] 'applied index is now lower than readState.Index' (duration: 14.91389ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:56:04.448189Z","caller":"traceutil/trace.go:171","msg":"trace[1042621025] transaction","detail":"{read_only:false; response_revision:9108; number_of_response:1; }","duration":"152.193337ms","start":"2026-06-23T04:56:04.295971Z","end":"2026-06-23T04:56:04.448164Z","steps":["trace[1042621025] 'process raft request' (duration: 152.067623ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:56:04.448176Z","caller":"traceutil/trace.go:171","msg":"trace[1927219625] transaction","detail":"{read_only:false; response_revision:9106; number_of_response:1; }","duration":"158.51791ms","start":"2026-06-23T04:56:04.289613Z","end":"2026-06-23T04:56:04.448131Z","steps":["trace[1927219625] 'process raft request' (duration: 158.209361ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:56:04.448281Z","caller":"traceutil/trace.go:171","msg":"trace[1580588106] transaction","detail":"{read_only:false; response_revision:9109; number_of_response:1; }","duration":"152.196427ms","start":"2026-06-23T04:56:04.296073Z","end":"2026-06-23T04:56:04.448269Z","steps":["trace[1580588106] 'process raft request' (duration: 152.005931ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:56:04.448243Z","caller":"traceutil/trace.go:171","msg":"trace[502191733] transaction","detail":"{read_only:false; response_revision:9107; number_of_response:1; }","duration":"155.606127ms","start":"2026-06-23T04:56:04.292627Z","end":"2026-06-23T04:56:04.448233Z","steps":["trace[502191733] 'process raft request' (duration: 155.370229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:56:04.448495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.846ms","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-23T04:56:04.448591Z","caller":"traceutil/trace.go:171","msg":"trace[1815937618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9109; }","duration":"112.997026ms","start":"2026-06-23T04:56:04.335578Z","end":"2026-06-23T04:56:04.448575Z","steps":["trace[1815937618] 'agreement among raft nodes before linearized reading' (duration: 112.750758ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:56:04.645905Z","caller":"traceutil/trace.go:171","msg":"trace[299674852] transaction","detail":"{read_only:false; response_revision:9111; number_of_response:1; }","duration":"181.678995ms","start":"2026-06-23T04:56:04.464188Z","end":"2026-06-23T04:56:04.645867Z","steps":["trace[299674852] 'process raft request' (duration: 181.391476ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:56:04.921434Z","caller":"traceutil/trace.go:171","msg":"trace[1341105658] transaction","detail":"{read_only:false; response_revision:9112; number_of_response:1; }","duration":"270.016227ms","start":"2026-06-23T04:56:04.651379Z","end":"2026-06-23T04:56:04.921395Z","steps":["trace[1341105658] 'process raft request' (duration: 242.578654ms)","trace[1341105658] 'compare' (duration: 27.164044ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:56:04.921755Z","caller":"traceutil/trace.go:171","msg":"trace[210632487] transaction","detail":"{read_only:false; response_revision:9113; number_of_response:1; }","duration":"269.617305ms","start":"2026-06-23T04:56:04.652116Z","end":"2026-06-23T04:56:04.921734Z","steps":["trace[210632487] 'process raft request' (duration: 269.213842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:56:05.038589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.916272ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-23T04:56:05.038836Z","caller":"traceutil/trace.go:171","msg":"trace[123733735] linearizableReadLoop","detail":"{readStateIndex:9443; appliedIndex:9441; }","duration":"385.949698ms","start":"2026-06-23T04:56:04.652856Z","end":"2026-06-23T04:56:05.038806Z","steps":["trace[123733735] 'read index received' (duration: 241.120748ms)","trace[123733735] 'applied index is now lower than readState.Index' (duration: 144.827709ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:56:05.058288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.414213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19572"} {"level":"info","ts":"2026-06-23T04:56:05.058366Z","caller":"traceutil/trace.go:171","msg":"trace[1907167348] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9114; }","duration":"405.524777ms","start":"2026-06-23T04:56:04.652823Z","end":"2026-06-23T04:56:05.058347Z","steps":["trace[1907167348] 'agreement among raft nodes before linearized reading' (duration: 386.33184ms)","trace[1907167348] 'range keys from in-memory index tree' (duration: 18.994351ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:56:05.058353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.872683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"warn","ts":"2026-06-23T04:56:05.058401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:56:04.65281Z","time spent":"405.582619ms","remote":"127.0.0.1:33014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19595,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-23T04:56:05.058437Z","caller":"traceutil/trace.go:171","msg":"trace[927667086] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:9114; }","duration":"109.008997ms","start":"2026-06-23T04:56:04.949406Z","end":"2026-06-23T04:56:05.058415Z","steps":["trace[927667086] 'agreement among raft nodes before linearized reading' (duration: 89.894622ms)","trace[927667086] 'range keys from in-memory index tree' (duration: 18.917609ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:57:00.227125Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-23T04:57:00.234519Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-23T04:57:00.234755Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-06-23T04:57:10.968448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.238191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T04:57:10.968971Z","caller":"traceutil/trace.go:171","msg":"trace[1673261287] linearizableReadLoop","detail":"{readStateIndex:10146; appliedIndex:10143; }","duration":"133.404545ms","start":"2026-06-23T04:57:10.835547Z","end":"2026-06-23T04:57:10.968952Z","steps":["trace[1673261287] 'read index received' (duration: 44.052µs)","trace[1673261287] 'applied index is now lower than readState.Index' (duration: 133.359613ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:57:10.968997Z","caller":"traceutil/trace.go:171","msg":"trace[1904592188] transaction","detail":"{read_only:false; response_revision:9796; number_of_response:1; }","duration":"183.232456ms","start":"2026-06-23T04:57:10.785725Z","end":"2026-06-23T04:57:10.968957Z","steps":["trace[1904592188] 'process raft request' (duration: 48.385405ms)","trace[1904592188] 'compare' (duration: 134.120017ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:57:10.968997Z","caller":"traceutil/trace.go:171","msg":"trace[503857098] transaction","detail":"{read_only:false; response_revision:9797; number_of_response:1; }","duration":"181.50214ms","start":"2026-06-23T04:57:10.787465Z","end":"2026-06-23T04:57:10.968967Z","steps":["trace[503857098] 'process raft request' (duration: 181.137688ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:57:10.969029Z","caller":"traceutil/trace.go:171","msg":"trace[1514609897] transaction","detail":"{read_only:false; response_revision:9798; number_of_response:1; }","duration":"181.254272ms","start":"2026-06-23T04:57:10.787734Z","end":"2026-06-23T04:57:10.968988Z","steps":["trace[1514609897] 'process raft request' (duration: 181.171459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:57:10.96913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.605951ms","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-23T04:57:10.969165Z","caller":"traceutil/trace.go:171","msg":"trace[924078313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9798; }","duration":"133.683303ms","start":"2026-06-23T04:57:10.835472Z","end":"2026-06-23T04:57:10.969155Z","steps":["trace[924078313] 'agreement among raft nodes before linearized reading' (duration: 133.554709ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:57:10.970684Z","caller":"traceutil/trace.go:171","msg":"trace[1783172706] transaction","detail":"{read_only:false; response_revision:9799; number_of_response:1; }","duration":"113.983796ms","start":"2026-06-23T04:57:10.856678Z","end":"2026-06-23T04:57:10.970662Z","steps":["trace[1783172706] 'process raft request' (duration: 113.759259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T04:57:25.716213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.327948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T04:57:25.71633Z","caller":"traceutil/trace.go:171","msg":"trace[666199963] linearizableReadLoop","detail":"{readStateIndex:10233; appliedIndex:10232; }","duration":"270.841197ms","start":"2026-06-23T04:57:25.445471Z","end":"2026-06-23T04:57:25.716312Z","steps":["trace[666199963] 'read index received' (duration: 128.290942ms)","trace[666199963] 'applied index is now lower than readState.Index' (duration: 142.549265ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:57:25.716431Z","caller":"traceutil/trace.go:171","msg":"trace[1573127324] transaction","detail":"{read_only:false; response_revision:9882; number_of_response:1; }","duration":"347.784178ms","start":"2026-06-23T04:57:25.368634Z","end":"2026-06-23T04:57:25.716419Z","steps":["trace[1573127324] 'process raft request' (duration: 205.15538ms)","trace[1573127324] 'compare' (duration: 142.216514ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T04:57:25.716493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T04:57:25.368614Z","time spent":"347.84998ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T04:57:25.716738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.2232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T04:57:25.716824Z","caller":"traceutil/trace.go:171","msg":"trace[76736350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9882; }","duration":"271.381554ms","start":"2026-06-23T04:57:25.445423Z","end":"2026-06-23T04:57:25.716805Z","steps":["trace[76736350] 'agreement among raft nodes before linearized reading' (duration: 271.212579ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T04:57:25.896521Z","caller":"traceutil/trace.go:171","msg":"trace[479084616] transaction","detail":"{read_only:false; response_revision:9883; number_of_response:1; }","duration":"129.454361ms","start":"2026-06-23T04:57:25.767032Z","end":"2026-06-23T04:57:25.896486Z","steps":["trace[479084616] 'process raft request' (duration: 108.612216ms)","trace[479084616] 'compare' (duration: 20.712651ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T04:58:46.278518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8050} {"level":"info","ts":"2026-06-23T04:58:46.349499Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8050,"took":"67.96465ms","hash":4125490173,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":27828224,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-23T04:58:46.349597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4125490173,"revision":8050,"compact-revision":5912} {"level":"warn","ts":"2026-06-23T05:02:46.789886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.702957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T05:02:46.789972Z","caller":"traceutil/trace.go:171","msg":"trace[238802432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12210; }","duration":"346.799019ms","start":"2026-06-23T05:02:46.443155Z","end":"2026-06-23T05:02:46.789954Z","steps":["trace[238802432] 'range keys from in-memory index tree' (duration: 346.629715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:02:46.79003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:02:46.443109Z","time spent":"346.911882ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-23T05:02:46.790151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.47654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7vvq5\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-06-23T05:02:46.79027Z","caller":"traceutil/trace.go:171","msg":"trace[1570274426] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7vvq5; range_end:; response_count:1; response_revision:12210; }","duration":"105.660284ms","start":"2026-06-23T05:02:46.68458Z","end":"2026-06-23T05:02:46.790241Z","steps":["trace[1570274426] 'range keys from in-memory index tree' (duration: 105.249725ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:03:15.11369Z","caller":"traceutil/trace.go:171","msg":"trace[67696564] linearizableReadLoop","detail":"{readStateIndex:12797; appliedIndex:12796; }","duration":"100.301862ms","start":"2026-06-23T05:03:15.013365Z","end":"2026-06-23T05:03:15.113667Z","steps":["trace[67696564] 'read index received' (duration: 100.083017ms)","trace[67696564] 'applied index is now lower than readState.Index' (duration: 217.825µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:03:15.113723Z","caller":"traceutil/trace.go:171","msg":"trace[1222628825] transaction","detail":"{read_only:false; response_revision:12366; number_of_response:1; }","duration":"142.04757ms","start":"2026-06-23T05:03:14.971653Z","end":"2026-06-23T05:03:15.113701Z","steps":["trace[1222628825] 'process raft request' (duration: 141.838175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:03:15.113905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.519737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7vvq5\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-06-23T05:03:15.113947Z","caller":"traceutil/trace.go:171","msg":"trace[1961632891] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7vvq5; range_end:; response_count:1; response_revision:12366; }","duration":"100.619199ms","start":"2026-06-23T05:03:15.013317Z","end":"2026-06-23T05:03:15.113936Z","steps":["trace[1961632891] 'agreement among raft nodes before linearized reading' (duration: 100.453645ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:03:15.482812Z","caller":"traceutil/trace.go:171","msg":"trace[1441841695] transaction","detail":"{read_only:false; response_revision:12367; number_of_response:1; }","duration":"178.068396ms","start":"2026-06-23T05:03:15.304702Z","end":"2026-06-23T05:03:15.482771Z","steps":["trace[1441841695] 'process raft request' (duration: 177.509354ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:03:46.286576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10414} {"level":"info","ts":"2026-06-23T05:03:46.384979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10414,"took":"95.782853ms","hash":2778228332,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":25899008,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-23T05:03:46.385062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2778228332,"revision":10414,"compact-revision":8050} {"level":"info","ts":"2026-06-23T05:08:46.292376Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12581} {"level":"info","ts":"2026-06-23T05:08:46.371459Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12581,"took":"76.732969ms","hash":3138235830,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":27897856,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-23T05:08:46.371552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3138235830,"revision":12581,"compact-revision":10414} {"level":"info","ts":"2026-06-23T05:09:48.823659Z","caller":"traceutil/trace.go:171","msg":"trace[1201143299] linearizableReadLoop","detail":"{readStateIndex:15558; appliedIndex:15557; }","duration":"319.017826ms","start":"2026-06-23T05:09:48.504608Z","end":"2026-06-23T05:09:48.823626Z","steps":["trace[1201143299] 'read index received' (duration: 318.670317ms)","trace[1201143299] 'applied index is now lower than readState.Index' (duration: 346.129µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:09:48.823737Z","caller":"traceutil/trace.go:171","msg":"trace[642447795] transaction","detail":"{read_only:false; response_revision:15034; number_of_response:1; }","duration":"381.906112ms","start":"2026-06-23T05:09:48.441792Z","end":"2026-06-23T05:09:48.823698Z","steps":["trace[642447795] 'process raft request' (duration: 381.607074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:48.823914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:09:48.441769Z","time spent":"382.048456ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11811,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T05:09:48.823983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.304433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T05:09:48.82405Z","caller":"traceutil/trace.go:171","msg":"trace[1030545800] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:15034; }","duration":"319.455588ms","start":"2026-06-23T05:09:48.504574Z","end":"2026-06-23T05:09:48.824029Z","steps":["trace[1030545800] 'agreement among raft nodes before linearized reading' (duration: 319.258242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:48.824103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:09:48.504531Z","time spent":"319.55603ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":33,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-06-23T05:09:48.824257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.398654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7vvq5\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-06-23T05:09:48.824291Z","caller":"traceutil/trace.go:171","msg":"trace[1013083079] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7vvq5; range_end:; response_count:1; response_revision:15034; }","duration":"122.490655ms","start":"2026-06-23T05:09:48.701789Z","end":"2026-06-23T05:09:48.82428Z","steps":["trace[1013083079] 'agreement among raft nodes before linearized reading' (duration: 122.343481ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:09:50.609874Z","caller":"traceutil/trace.go:171","msg":"trace[149611614] transaction","detail":"{read_only:false; response_revision:15049; number_of_response:1; }","duration":"149.485988ms","start":"2026-06-23T05:09:50.46036Z","end":"2026-06-23T05:09:50.609846Z","steps":["trace[149611614] 'process raft request' (duration: 149.321384ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:09:50.620626Z","caller":"traceutil/trace.go:171","msg":"trace[1682077427] transaction","detail":"{read_only:false; response_revision:15050; number_of_response:1; }","duration":"150.171457ms","start":"2026-06-23T05:09:50.470419Z","end":"2026-06-23T05:09:50.620591Z","steps":["trace[1682077427] 'process raft request' (duration: 149.960041ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:09:59.12438Z","caller":"traceutil/trace.go:171","msg":"trace[1826462665] linearizableReadLoop","detail":"{readStateIndex:15739; appliedIndex:15738; }","duration":"201.05548ms","start":"2026-06-23T05:09:58.923299Z","end":"2026-06-23T05:09:59.124355Z","steps":["trace[1826462665] 'read index received' (duration: 158.632695ms)","trace[1826462665] 'applied index is now lower than readState.Index' (duration: 42.421725ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:09:59.124489Z","caller":"traceutil/trace.go:171","msg":"trace[834743023] transaction","detail":"{read_only:false; response_revision:15211; number_of_response:1; }","duration":"375.294211ms","start":"2026-06-23T05:09:58.749161Z","end":"2026-06-23T05:09:59.124455Z","steps":["trace[834743023] 'process raft request' (duration: 332.859425ms)","trace[834743023] 'compare' (duration: 42.126868ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T05:09:59.124606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.39678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-xl9ft.18bb9da22ed8cc84\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-06-23T05:09:59.124633Z","caller":"traceutil/trace.go:171","msg":"trace[1918657211] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-xl9ft.18bb9da22ed8cc84; range_end:; response_count:1; response_revision:15211; }","duration":"145.467242ms","start":"2026-06-23T05:09:58.979157Z","end":"2026-06-23T05:09:59.124624Z","steps":["trace[1918657211] 'agreement among raft nodes before linearized reading' (duration: 145.339909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:59.124704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.373938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7vvq5\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-06-23T05:09:59.124834Z","caller":"traceutil/trace.go:171","msg":"trace[1577220213] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7vvq5; range_end:; response_count:1; response_revision:15211; }","duration":"201.555873ms","start":"2026-06-23T05:09:58.923251Z","end":"2026-06-23T05:09:59.124807Z","steps":["trace[1577220213] 'agreement among raft nodes before linearized reading' (duration: 201.238755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:59.124684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:09:58.749133Z","time spent":"375.444815ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3708,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T05:09:59.782766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103938280514697,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T05:09:59.890915Z","caller":"traceutil/trace.go:171","msg":"trace[1405411843] linearizableReadLoop","detail":"{readStateIndex:15741; appliedIndex:15740; }","duration":"608.970607ms","start":"2026-06-23T05:09:59.281911Z","end":"2026-06-23T05:09:59.890882Z","steps":["trace[1405411843] 'read index received' (duration: 608.879725ms)","trace[1405411843] 'applied index is now lower than readState.Index' (duration: 89.532µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:09:59.89103Z","caller":"traceutil/trace.go:171","msg":"trace[696957527] transaction","detail":"{read_only:false; response_revision:15213; number_of_response:1; }","duration":"670.248667ms","start":"2026-06-23T05:09:59.220772Z","end":"2026-06-23T05:09:59.891021Z","steps":["trace[696957527] 'process raft request' (duration: 669.928928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:59.891175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:09:59.220741Z","time spent":"670.313159ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7182,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T05:09:59.891499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.555772ms","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-23T05:09:59.89164Z","caller":"traceutil/trace.go:171","msg":"trace[1141510937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15213; }","duration":"609.757018ms","start":"2026-06-23T05:09:59.281857Z","end":"2026-06-23T05:09:59.891614Z","steps":["trace[1141510937] 'agreement among raft nodes before linearized reading' (duration: 609.448679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:59.891695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:09:59.281831Z","time spent":"609.85173ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-23T05:09:59.895901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.549391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T05:09:59.895956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.810668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T05:09:59.895995Z","caller":"traceutil/trace.go:171","msg":"trace[2077604966] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:15214; }","duration":"171.691394ms","start":"2026-06-23T05:09:59.72428Z","end":"2026-06-23T05:09:59.895971Z","steps":["trace[2077604966] 'agreement among raft nodes before linearized reading' (duration: 171.5209ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:09:59.896029Z","caller":"traceutil/trace.go:171","msg":"trace[2140461937] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:15214; }","duration":"461.89148ms","start":"2026-06-23T05:09:59.434118Z","end":"2026-06-23T05:09:59.896009Z","steps":["trace[2140461937] 'agreement among raft nodes before linearized reading' (duration: 461.661164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:59.896041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.488265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2648f8a1-8cc3-4f5f-92d9-b3147af12d10\" ","response":"range_response_count:1 size:3723"} {"level":"warn","ts":"2026-06-23T05:09:59.896045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.057198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T05:09:59.895917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.975818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc8c5d4f-8327-48c9-99b1-dd3abc8ba11c\" ","response":"range_response_count:1 size:3726"} {"level":"info","ts":"2026-06-23T05:09:59.896102Z","caller":"traceutil/trace.go:171","msg":"trace[134159517] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2648f8a1-8cc3-4f5f-92d9-b3147af12d10; range_end:; response_count:1; response_revision:15214; }","duration":"592.610558ms","start":"2026-06-23T05:09:59.303476Z","end":"2026-06-23T05:09:59.896087Z","steps":["trace[134159517] 'agreement among raft nodes before linearized reading' (duration: 592.362082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:59.896102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.14971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-23T05:09:59.896122Z","caller":"traceutil/trace.go:171","msg":"trace[1136680811] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cc8c5d4f-8327-48c9-99b1-dd3abc8ba11c; range_end:; response_count:1; response_revision:15214; }","duration":"593.232116ms","start":"2026-06-23T05:09:59.302879Z","end":"2026-06-23T05:09:59.896111Z","steps":["trace[1136680811] 'agreement among raft nodes before linearized reading' (duration: 592.850126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:59.896136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:09:59.303452Z","time spent":"592.67898ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3746,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2648f8a1-8cc3-4f5f-92d9-b3147af12d10\" "} {"level":"info","ts":"2026-06-23T05:09:59.896133Z","caller":"traceutil/trace.go:171","msg":"trace[1345205132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15214; }","duration":"452.166411ms","start":"2026-06-23T05:09:59.443941Z","end":"2026-06-23T05:09:59.896107Z","steps":["trace[1345205132] 'agreement among raft nodes before linearized reading' (duration: 452.030387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:59.896151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:09:59.302859Z","time spent":"593.285667ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3749,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc8c5d4f-8327-48c9-99b1-dd3abc8ba11c\" "} {"level":"info","ts":"2026-06-23T05:09:59.896151Z","caller":"traceutil/trace.go:171","msg":"trace[1013587408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15214; }","duration":"242.280284ms","start":"2026-06-23T05:09:59.653856Z","end":"2026-06-23T05:09:59.896136Z","steps":["trace[1013587408] 'agreement among raft nodes before linearized reading' (duration: 242.14634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:09:59.896177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:09:59.44393Z","time spent":"452.239822ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-23T05:09:59.895955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.187487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-23T05:09:59.896076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:09:59.43405Z","time spent":"462.014074ms","remote":"127.0.0.1:34898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-23T05:09:59.896255Z","caller":"traceutil/trace.go:171","msg":"trace[871956403] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:15214; }","duration":"277.538127ms","start":"2026-06-23T05:09:59.618705Z","end":"2026-06-23T05:09:59.896243Z","steps":["trace[871956403] 'agreement among raft nodes before linearized reading' (duration: 277.054674ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:11:06.591241Z","caller":"traceutil/trace.go:171","msg":"trace[2141219308] transaction","detail":"{read_only:false; response_revision:16133; number_of_response:1; }","duration":"196.962551ms","start":"2026-06-23T05:11:06.394255Z","end":"2026-06-23T05:11:06.591217Z","steps":["trace[2141219308] 'process raft request' (duration: 196.785147ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:11:06.593228Z","caller":"traceutil/trace.go:171","msg":"trace[1157699046] linearizableReadLoop","detail":"{readStateIndex:16693; appliedIndex:16692; }","duration":"195.953074ms","start":"2026-06-23T05:11:06.39725Z","end":"2026-06-23T05:11:06.593203Z","steps":["trace[1157699046] 'read index received' (duration: 194.066542ms)","trace[1157699046] 'applied index is now lower than readState.Index' (duration: 1.884762ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:11:06.593263Z","caller":"traceutil/trace.go:171","msg":"trace[742747939] transaction","detail":"{read_only:false; response_revision:16134; number_of_response:1; }","duration":"198.026501ms","start":"2026-06-23T05:11:06.395226Z","end":"2026-06-23T05:11:06.593252Z","steps":["trace[742747939] 'process raft request' (duration: 197.860106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:11:06.593437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.1766ms","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-23T05:11:06.593467Z","caller":"traceutil/trace.go:171","msg":"trace[1906594546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16134; }","duration":"196.242542ms","start":"2026-06-23T05:11:06.397218Z","end":"2026-06-23T05:11:06.593461Z","steps":["trace[1906594546] 'agreement among raft nodes before linearized reading' (duration: 196.071777ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:11:42.550291Z","caller":"traceutil/trace.go:171","msg":"trace[1792734392] transaction","detail":"{read_only:false; response_revision:16320; number_of_response:1; }","duration":"103.522716ms","start":"2026-06-23T05:11:42.446744Z","end":"2026-06-23T05:11:42.550267Z","steps":["trace[1792734392] 'process raft request' (duration: 103.416683ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:13:46.298915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14618} {"level":"info","ts":"2026-06-23T05:13:46.376872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14618,"took":"75.189285ms","hash":2235763334,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":32247808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-23T05:13:46.376937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2235763334,"revision":14618,"compact-revision":12581} {"level":"info","ts":"2026-06-23T05:17:19.52966Z","caller":"traceutil/trace.go:171","msg":"trace[2128302083] transaction","detail":"{read_only:false; response_revision:18764; number_of_response:1; }","duration":"268.39277ms","start":"2026-06-23T05:17:19.261231Z","end":"2026-06-23T05:17:19.529623Z","steps":["trace[2128302083] 'process raft request' (duration: 268.167824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:20.218295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.842411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-23T05:17:20.218406Z","caller":"traceutil/trace.go:171","msg":"trace[1290739448] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:18768; }","duration":"325.030066ms","start":"2026-06-23T05:17:19.893352Z","end":"2026-06-23T05:17:20.218383Z","steps":["trace[1290739448] 'count revisions from in-memory index tree' (duration: 324.629315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:20.218453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:19.893324Z","time spent":"325.116739ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-06-23T05:17:20.218465Z","caller":"traceutil/trace.go:171","msg":"trace[654899693] transaction","detail":"{read_only:false; response_revision:18769; number_of_response:1; }","duration":"299.914938ms","start":"2026-06-23T05:17:19.918509Z","end":"2026-06-23T05:17:20.218424Z","steps":["trace[654899693] 'process raft request' (duration: 276.694146ms)","trace[654899693] 'compare' (duration: 21.995766ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T05:17:20.218649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:19.918483Z","time spent":"300.081433ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T05:17:20.741796Z","caller":"traceutil/trace.go:171","msg":"trace[1114560461] transaction","detail":"{read_only:false; response_revision:18770; number_of_response:1; }","duration":"370.627897ms","start":"2026-06-23T05:17:20.371138Z","end":"2026-06-23T05:17:20.741766Z","steps":["trace[1114560461] 'process raft request' (duration: 368.33074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:20.741962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.37111Z","time spent":"370.775442ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T05:17:20.742172Z","caller":"traceutil/trace.go:171","msg":"trace[1253067930] linearizableReadLoop","detail":"{readStateIndex:19418; appliedIndex:19417; }","duration":"337.285917ms","start":"2026-06-23T05:17:20.404874Z","end":"2026-06-23T05:17:20.74216Z","steps":["trace[1253067930] 'read index received' (duration: 334.523026ms)","trace[1253067930] 'applied index is now lower than readState.Index' (duration: 2.761501ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T05:17:20.742587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.088523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-23T05:17:20.742684Z","caller":"traceutil/trace.go:171","msg":"trace[997370780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18770; }","duration":"317.29355ms","start":"2026-06-23T05:17:20.425367Z","end":"2026-06-23T05:17:20.742661Z","steps":["trace[997370780] 'agreement among raft nodes before linearized reading' (duration: 316.989801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:20.742734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.425343Z","time spent":"317.379892ms","remote":"127.0.0.1:33098","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-23T05:17:20.742868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.748653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-23T05:17:20.742904Z","caller":"traceutil/trace.go:171","msg":"trace[1500398935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18770; }","duration":"299.802785ms","start":"2026-06-23T05:17:20.443092Z","end":"2026-06-23T05:17:20.742895Z","steps":["trace[1500398935] 'agreement among raft nodes before linearized reading' (duration: 299.747234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:20.743175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.280617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-23T05:17:20.743281Z","caller":"traceutil/trace.go:171","msg":"trace[423563907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18770; }","duration":"338.463122ms","start":"2026-06-23T05:17:20.404796Z","end":"2026-06-23T05:17:20.74326Z","steps":["trace[423563907] 'agreement among raft nodes before linearized reading' (duration: 337.816373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:20.743329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.404767Z","time spent":"338.552075ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-23T05:17:21.274026Z","caller":"traceutil/trace.go:171","msg":"trace[514814857] linearizableReadLoop","detail":"{readStateIndex:19421; appliedIndex:19419; }","duration":"452.233877ms","start":"2026-06-23T05:17:20.821773Z","end":"2026-06-23T05:17:21.274007Z","steps":["trace[514814857] 'read index received' (duration: 414.009103ms)","trace[514814857] 'applied index is now lower than readState.Index' (duration: 38.224104ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:17:21.274064Z","caller":"traceutil/trace.go:171","msg":"trace[1091882835] transaction","detail":"{read_only:false; response_revision:18773; number_of_response:1; }","duration":"518.542037ms","start":"2026-06-23T05:17:20.755496Z","end":"2026-06-23T05:17:21.274038Z","steps":["trace[1091882835] 'process raft request' (duration: 518.424813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:21.274186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.755487Z","time spent":"518.630089ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T05:17:21.274232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.40821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-23T05:17:21.274263Z","caller":"traceutil/trace.go:171","msg":"trace[837174985] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18773; }","duration":"456.466941ms","start":"2026-06-23T05:17:20.817789Z","end":"2026-06-23T05:17:21.274256Z","steps":["trace[837174985] 'agreement among raft nodes before linearized reading' (duration: 456.309716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:21.274288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.817783Z","time spent":"456.498553ms","remote":"127.0.0.1:32998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-06-23T05:17:21.274369Z","caller":"traceutil/trace.go:171","msg":"trace[895663709] transaction","detail":"{read_only:false; response_revision:18772; number_of_response:1; }","duration":"521.144423ms","start":"2026-06-23T05:17:20.753215Z","end":"2026-06-23T05:17:21.274359Z","steps":["trace[895663709] 'process raft request' (duration: 482.465086ms)","trace[895663709] 'compare' (duration: 37.297926ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T05:17:21.274413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.753206Z","time spent":"521.187094ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T05:17:21.274476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.284007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-23T05:17:21.274503Z","caller":"traceutil/trace.go:171","msg":"trace[1455181874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18773; }","duration":"521.319098ms","start":"2026-06-23T05:17:20.753177Z","end":"2026-06-23T05:17:21.274496Z","steps":["trace[1455181874] 'agreement among raft nodes before linearized reading' (duration: 521.254326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:21.27452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.753172Z","time spent":"521.343809ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-23T05:17:21.274621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.841722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-23T05:17:21.274762Z","caller":"traceutil/trace.go:171","msg":"trace[1276345488] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18773; }","duration":"456.973617ms","start":"2026-06-23T05:17:20.817713Z","end":"2026-06-23T05:17:21.274686Z","steps":["trace[1276345488] 'agreement among raft nodes before linearized reading' (duration: 456.591985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:21.274809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.81769Z","time spent":"457.10872ms","remote":"127.0.0.1:32998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-06-23T05:17:21.278364Z","caller":"traceutil/trace.go:171","msg":"trace[1536920455] transaction","detail":"{read_only:false; response_revision:18774; number_of_response:1; }","duration":"452.062272ms","start":"2026-06-23T05:17:20.82629Z","end":"2026-06-23T05:17:21.278352Z","steps":["trace[1536920455] 'process raft request' (duration: 452.00735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:21.278457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.82627Z","time spent":"452.136234ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T05:17:21.278616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.871632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-23T05:17:21.278734Z","caller":"traceutil/trace.go:171","msg":"trace[1973839224] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:18774; }","duration":"376.009146ms","start":"2026-06-23T05:17:20.902704Z","end":"2026-06-23T05:17:21.278713Z","steps":["trace[1973839224] 'agreement among raft nodes before linearized reading' (duration: 375.751279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:21.278792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.902592Z","time spent":"376.188821ms","remote":"127.0.0.1:60870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-06-23T05:17:21.278826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.358371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-23T05:17:21.278927Z","caller":"traceutil/trace.go:171","msg":"trace[916863178] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:18774; }","duration":"268.545537ms","start":"2026-06-23T05:17:21.010358Z","end":"2026-06-23T05:17:21.278904Z","steps":["trace[916863178] 'agreement among raft nodes before linearized reading' (duration: 268.335001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:21.279012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.2198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-23T05:17:21.279051Z","caller":"traceutil/trace.go:171","msg":"trace[1009691447] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:18774; }","duration":"364.319872ms","start":"2026-06-23T05:17:20.914721Z","end":"2026-06-23T05:17:21.279041Z","steps":["trace[1009691447] 'agreement among raft nodes before linearized reading' (duration: 364.25092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:21.279078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:17:20.914678Z","time spent":"364.395085ms","remote":"127.0.0.1:33084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":5,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-06-23T05:17:21.280341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.957243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-xn6mj\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-06-23T05:17:21.280411Z","caller":"traceutil/trace.go:171","msg":"trace[83630769] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-xn6mj; range_end:; response_count:1; response_revision:18774; }","duration":"230.074745ms","start":"2026-06-23T05:17:21.050314Z","end":"2026-06-23T05:17:21.280389Z","steps":["trace[83630769] 'agreement among raft nodes before linearized reading' (duration: 229.867489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:17:22.962525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.086515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-23T05:17:22.963034Z","caller":"traceutil/trace.go:171","msg":"trace[1688572243] transaction","detail":"{read_only:false; response_revision:18795; number_of_response:1; }","duration":"132.210389ms","start":"2026-06-23T05:17:22.830802Z","end":"2026-06-23T05:17:22.963013Z","steps":["trace[1688572243] 'process raft request' (duration: 16.545097ms)","trace[1688572243] 'compare' (duration: 114.957241ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:18:17.762467Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-23T05:18:17.766775Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-23T05:18:17.766855Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-23T05:18:46.307761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17094} {"level":"info","ts":"2026-06-23T05:18:46.411119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17094,"took":"100.579047ms","hash":899203283,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":34136064,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-23T05:18:46.411225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":899203283,"revision":17094,"compact-revision":14618} {"level":"info","ts":"2026-06-23T05:23:46.316931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19688} {"level":"info","ts":"2026-06-23T05:23:46.439762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19688,"took":"119.264678ms","hash":1907281467,"current-db-size-bytes":57782272,"current-db-size":"58 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-23T05:23:46.43984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1907281467,"revision":19688,"compact-revision":17094} {"level":"info","ts":"2026-06-23T05:23:49.875426Z","caller":"traceutil/trace.go:171","msg":"trace[1073340166] transaction","detail":"{read_only:false; response_revision:21748; number_of_response:1; }","duration":"120.315851ms","start":"2026-06-23T05:23:49.755085Z","end":"2026-06-23T05:23:49.875401Z","steps":["trace[1073340166] 'process raft request' (duration: 120.144346ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:23:52.269349Z","caller":"traceutil/trace.go:171","msg":"trace[1214209587] transaction","detail":"{read_only:false; response_revision:21758; number_of_response:1; }","duration":"388.565796ms","start":"2026-06-23T05:23:51.880749Z","end":"2026-06-23T05:23:52.269315Z","steps":["trace[1214209587] 'process raft request' (duration: 388.410781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:52.26952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:23:51.880732Z","time spent":"388.696841ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T05:23:56.289069Z","caller":"traceutil/trace.go:171","msg":"trace[1992520937] linearizableReadLoop","detail":"{readStateIndex:22526; appliedIndex:22525; }","duration":"289.523204ms","start":"2026-06-23T05:23:55.999524Z","end":"2026-06-23T05:23:56.289047Z","steps":["trace[1992520937] 'read index received' (duration: 289.435722ms)","trace[1992520937] 'applied index is now lower than readState.Index' (duration: 86.652µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:23:56.289221Z","caller":"traceutil/trace.go:171","msg":"trace[1592515526] transaction","detail":"{read_only:false; response_revision:21778; number_of_response:1; }","duration":"305.441859ms","start":"2026-06-23T05:23:55.983742Z","end":"2026-06-23T05:23:56.289183Z","steps":["trace[1592515526] 'process raft request' (duration: 305.187441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:56.289313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.756941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-23T05:23:56.289386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:23:55.98371Z","time spent":"305.586563ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T05:23:56.289394Z","caller":"traceutil/trace.go:171","msg":"trace[705677641] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:21778; }","duration":"289.903445ms","start":"2026-06-23T05:23:55.999471Z","end":"2026-06-23T05:23:56.289375Z","steps":["trace[705677641] 'agreement among raft nodes before linearized reading' (duration: 289.71545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:56.496516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.740221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-23T05:23:56.496665Z","caller":"traceutil/trace.go:171","msg":"trace[861826703] linearizableReadLoop","detail":"{readStateIndex:22527; appliedIndex:22526; }","duration":"207.523528ms","start":"2026-06-23T05:23:56.289124Z","end":"2026-06-23T05:23:56.496647Z","steps":["trace[861826703] 'read index received' (duration: 8.484988ms)","trace[861826703] 'applied index is now lower than readState.Index' (duration: 199.03669ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:23:56.496874Z","caller":"traceutil/trace.go:171","msg":"trace[1420113337] transaction","detail":"{read_only:false; response_revision:21779; number_of_response:1; }","duration":"215.744438ms","start":"2026-06-23T05:23:56.281074Z","end":"2026-06-23T05:23:56.496818Z","steps":["trace[1420113337] 'process raft request' (duration: 16.584895ms)","trace[1420113337] 'compare' (duration: 198.518053ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T05:23:56.496967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.742365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-23T05:23:56.49702Z","caller":"traceutil/trace.go:171","msg":"trace[803439201] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:21779; }","duration":"478.833977ms","start":"2026-06-23T05:23:56.01817Z","end":"2026-06-23T05:23:56.497004Z","steps":["trace[803439201] 'agreement among raft nodes before linearized reading' (duration: 478.716494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:56.497062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:23:56.018154Z","time spent":"478.89785ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":27,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-06-23T05:23:56.889052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.837055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-11b75165-c0fb-4aeb-8ee0-d01c65ae8e16\" ","response":"range_response_count:1 size:1316"} {"level":"info","ts":"2026-06-23T05:23:56.889183Z","caller":"traceutil/trace.go:171","msg":"trace[424759530] range","detail":"{range_begin:/registry/persistentvolumes/pvc-11b75165-c0fb-4aeb-8ee0-d01c65ae8e16; range_end:; response_count:1; response_revision:21779; }","duration":"291.962708ms","start":"2026-06-23T05:23:56.597163Z","end":"2026-06-23T05:23:56.889126Z","steps":["trace[424759530] 'range keys from in-memory index tree' (duration: 291.658549ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:23:57.252437Z","caller":"traceutil/trace.go:171","msg":"trace[954734328] transaction","detail":"{read_only:false; response_revision:21780; number_of_response:1; }","duration":"130.457341ms","start":"2026-06-23T05:23:57.121631Z","end":"2026-06-23T05:23:57.252089Z","steps":["trace[954734328] 'process raft request' (duration: 130.319207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:57.663746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.005402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-23T05:23:57.664143Z","caller":"traceutil/trace.go:171","msg":"trace[661414862] transaction","detail":"{read_only:false; response_revision:21781; number_of_response:1; }","duration":"480.833749ms","start":"2026-06-23T05:23:57.183264Z","end":"2026-06-23T05:23:57.664097Z","steps":["trace[661414862] 'process raft request' (duration: 295.370163ms)","trace[661414862] 'compare' (duration: 184.566319ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T05:23:57.664306Z","caller":"traceutil/trace.go:171","msg":"trace[302298438] linearizableReadLoop","detail":"{readStateIndex:22531; appliedIndex:22528; }","duration":"276.154397ms","start":"2026-06-23T05:23:57.388137Z","end":"2026-06-23T05:23:57.664291Z","steps":["trace[302298438] 'read index received' (duration: 90.498975ms)","trace[302298438] 'applied index is now lower than readState.Index' (duration: 185.654322ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T05:23:57.664317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:23:57.183245Z","time spent":"480.981813ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T05:23:57.664343Z","caller":"traceutil/trace.go:171","msg":"trace[2109078710] transaction","detail":"{read_only:false; response_revision:21782; number_of_response:1; }","duration":"316.080213ms","start":"2026-06-23T05:23:57.348232Z","end":"2026-06-23T05:23:57.664312Z","steps":["trace[2109078710] 'process raft request' (duration: 315.836765ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:23:57.664429Z","caller":"traceutil/trace.go:171","msg":"trace[43177775] transaction","detail":"{read_only:false; response_revision:21783; number_of_response:1; }","duration":"287.752411ms","start":"2026-06-23T05:23:57.376623Z","end":"2026-06-23T05:23:57.664375Z","steps":["trace[43177775] 'process raft request' (duration: 287.627317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:57.664492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:23:57.348211Z","time spent":"316.201297ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T05:23:57.664512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.926209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-23T05:23:57.664557Z","caller":"traceutil/trace.go:171","msg":"trace[651800689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21783; }","duration":"272.026662ms","start":"2026-06-23T05:23:57.392523Z","end":"2026-06-23T05:23:57.66455Z","steps":["trace[651800689] 'agreement among raft nodes before linearized reading' (duration: 271.873967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:57.664517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.847053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-23T05:23:57.664616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.614418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-23T05:23:57.664634Z","caller":"traceutil/trace.go:171","msg":"trace[1664864346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21783; }","duration":"219.002168ms","start":"2026-06-23T05:23:57.445607Z","end":"2026-06-23T05:23:57.664609Z","steps":["trace[1664864346] 'agreement among raft nodes before linearized reading' (duration: 218.794611ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:23:57.664686Z","caller":"traceutil/trace.go:171","msg":"trace[663245177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21783; }","duration":"268.721862ms","start":"2026-06-23T05:23:57.395945Z","end":"2026-06-23T05:23:57.664667Z","steps":["trace[663245177] 'agreement among raft nodes before linearized reading' (duration: 268.495915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:57.664682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.052933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-23T05:23:57.664759Z","caller":"traceutil/trace.go:171","msg":"trace[249725758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21783; }","duration":"272.152786ms","start":"2026-06-23T05:23:57.392586Z","end":"2026-06-23T05:23:57.664738Z","steps":["trace[249725758] 'agreement among raft nodes before linearized reading' (duration: 271.864897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:57.665277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.129938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-23T05:23:57.665344Z","caller":"traceutil/trace.go:171","msg":"trace[1554465229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21783; }","duration":"277.192979ms","start":"2026-06-23T05:23:57.38813Z","end":"2026-06-23T05:23:57.665323Z","steps":["trace[1554465229] 'agreement among raft nodes before linearized reading' (duration: 276.259851ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T05:23:58.616001Z","caller":"traceutil/trace.go:171","msg":"trace[760736248] transaction","detail":"{read_only:false; response_revision:21789; number_of_response:1; }","duration":"114.059623ms","start":"2026-06-23T05:23:58.501915Z","end":"2026-06-23T05:23:58.615974Z","steps":["trace[760736248] 'process raft request' (duration: 113.842236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T05:23:59.026396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.218217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-23T05:23:59.026599Z","caller":"traceutil/trace.go:171","msg":"trace[460564963] transaction","detail":"{read_only:false; response_revision:21790; number_of_response:1; }","duration":"384.006252ms","start":"2026-06-23T05:23:58.64256Z","end":"2026-06-23T05:23:59.026566Z","steps":["trace[460564963] 'process raft request' (duration: 158.538457ms)","trace[460564963] 'compare' (duration: 224.829555ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T05:23:59.026741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T05:23:58.642514Z","time spent":"384.152646ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"}