{"level":"warn","ts":"2026-04-16T16:33:35.564698Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:33:35.5648Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.146:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.146:2380","--initial-cluster=instance=https://199.19.213.146:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.146:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.146: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-04-16T16:33:35.564871Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:33:35.564885Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-04-16T16:33:35.564906Z","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-04-16T16:33:35.565612Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"]} {"level":"info","ts":"2026-04-16T16:33:35.565697Z","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.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.146: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-04-16T16:33:35.569648Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.719904ms"} {"level":"info","ts":"2026-04-16T16:33:35.575574Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c8e9c1aa9bb537b7","cluster-id":"933c0a8774274c15"} {"level":"info","ts":"2026-04-16T16:33:35.575672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:33:35.575692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 0"} {"level":"info","ts":"2026-04-16T16:33:35.575709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c8e9c1aa9bb537b7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:33:35.575719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 1"} {"level":"info","ts":"2026-04-16T16:33:35.575756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"warn","ts":"2026-04-16T16:33:35.581453Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:33:35.583076Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:33:35.584519Z","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-04-16T16:33:35.587941Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c8e9c1aa9bb537b7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:33:35.588172Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c8e9c1aa9bb537b7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:33:35.588237Z","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-04-16T16:33:35.588416Z","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-04-16T16:33:35.588443Z","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-04-16T16:33:35.590056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"info","ts":"2026-04-16T16:33:35.591118Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","added-peer-id":"c8e9c1aa9bb537b7","added-peer-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-04-16T16:33:35.592972Z","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-04-16T16:33:35.593086Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-04-16T16:33:35.593221Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-04-16T16:33:35.593307Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c8e9c1aa9bb537b7","initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:33:35.593375Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:33:36.576759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:33:36.576827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:33:36.576849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgPreVoteResp from c8e9c1aa9bb537b7 at term 1"} {"level":"info","ts":"2026-04-16T16:33:36.576867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:33:36.576878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgVoteResp from c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-04-16T16:33:36.576894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became leader at term 2"} {"level":"info","ts":"2026-04-16T16:33:36.576907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c8e9c1aa9bb537b7 elected leader c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-04-16T16:33:36.578025Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:33:36.578892Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c8e9c1aa9bb537b7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.146:2379]}","request-path":"/0/members/c8e9c1aa9bb537b7/attributes","cluster-id":"933c0a8774274c15","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:33:36.579038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:33:36.579085Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:33:36.579258Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:33:36.579329Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:33:36.579358Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T16:33:36.579403Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:33:36.579436Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:33:36.581411Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T16:33:36.58142Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.146:2379"} {"level":"warn","ts":"2026-04-16T16:33:48.429094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.291628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:48.429217Z","caller":"traceutil/trace.go:171","msg":"trace[2140024104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:361; }","duration":"174.484302ms","start":"2026-04-16T16:33:48.254718Z","end":"2026-04-16T16:33:48.429203Z","steps":["trace[2140024104] 'range keys from in-memory index tree' (duration: 174.195645ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:48.750289Z","caller":"traceutil/trace.go:171","msg":"trace[113449992] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"273.747969ms","start":"2026-04-16T16:33:48.476513Z","end":"2026-04-16T16:33:48.750261Z","steps":["trace[113449992] 'read index received' (duration: 273.518554ms)","trace[113449992] 'applied index is now lower than readState.Index' (duration: 228.485µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:48.750372Z","caller":"traceutil/trace.go:171","msg":"trace[2089073523] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"298.947293ms","start":"2026-04-16T16:33:48.451387Z","end":"2026-04-16T16:33:48.750334Z","steps":["trace[2089073523] 'process raft request' (duration: 298.726268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:48.750418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.891142ms","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-04-16T16:33:48.750455Z","caller":"traceutil/trace.go:171","msg":"trace[1389897557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:362; }","duration":"273.982444ms","start":"2026-04-16T16:33:48.476462Z","end":"2026-04-16T16:33:48.750444Z","steps":["trace[1389897557] 'agreement among raft nodes before linearized reading' (duration: 273.900662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:49.19955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.994763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:33:49.199642Z","caller":"traceutil/trace.go:171","msg":"trace[170842790] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:372; }","duration":"355.535981ms","start":"2026-04-16T16:33:48.844093Z","end":"2026-04-16T16:33:49.199629Z","steps":["trace[170842790] 'read index received' (duration: 157.886272ms)","trace[170842790] 'applied index is now lower than readState.Index' (duration: 197.648819ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:49.199715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.618554ms","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-04-16T16:33:49.19974Z","caller":"traceutil/trace.go:171","msg":"trace[904796810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:363; }","duration":"355.690445ms","start":"2026-04-16T16:33:48.844043Z","end":"2026-04-16T16:33:49.199733Z","steps":["trace[904796810] 'agreement among raft nodes before linearized reading' (duration: 355.625944ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:49.199711Z","caller":"traceutil/trace.go:171","msg":"trace[2096900280] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"445.006818ms","start":"2026-04-16T16:33:48.75466Z","end":"2026-04-16T16:33:49.199667Z","steps":["trace[2096900280] 'process raft request' (duration: 247.423681ms)","trace[2096900280] 'compare' (duration: 196.86075ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:49.199815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:48.844018Z","time spent":"355.745406ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:33:49.199907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:48.754635Z","time spent":"445.157132ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:49.357058Z","caller":"traceutil/trace.go:171","msg":"trace[396847056] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"152.814758ms","start":"2026-04-16T16:33:49.204219Z","end":"2026-04-16T16:33:49.357034Z","steps":["trace[396847056] 'process raft request' (duration: 152.560892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:34:13.494384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.820641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:34:13.494883Z","caller":"traceutil/trace.go:171","msg":"trace[203375984] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"300.316926ms","start":"2026-04-16T16:34:13.194533Z","end":"2026-04-16T16:34:13.49485Z","steps":["trace[203375984] 'process raft request' (duration: 181.456081ms)","trace[203375984] 'compare' (duration: 117.552875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:34:13.494997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:34:13.19452Z","time spent":"300.423248ms","remote":"127.0.0.1:50928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:34:13.612566Z","caller":"traceutil/trace.go:171","msg":"trace[680022455] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"414.700579ms","start":"2026-04-16T16:34:13.197822Z","end":"2026-04-16T16:34:13.612523Z","steps":["trace[680022455] 'process raft request' (duration: 404.501268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:34:13.612836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:34:13.197813Z","time spent":"414.879583ms","remote":"127.0.0.1:50928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:34:13.613057Z","caller":"traceutil/trace.go:171","msg":"trace[1275699614] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"394.922769ms","start":"2026-04-16T16:34:13.218085Z","end":"2026-04-16T16:34:13.613008Z","steps":["trace[1275699614] 'process raft request' (duration: 394.331045ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:13.613129Z","caller":"traceutil/trace.go:171","msg":"trace[1924869396] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"331.446755ms","start":"2026-04-16T16:34:13.281644Z","end":"2026-04-16T16:34:13.613091Z","steps":["trace[1924869396] 'process raft request' (duration: 331.150718ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:13.613281Z","caller":"traceutil/trace.go:171","msg":"trace[1013777708] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:528; }","duration":"358.459799ms","start":"2026-04-16T16:34:13.254767Z","end":"2026-04-16T16:34:13.613227Z","steps":["trace[1013777708] 'read index received' (duration: 121.404923ms)","trace[1013777708] 'applied index is now lower than readState.Index' (duration: 237.052986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:34:13.613275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:34:13.281605Z","time spent":"331.595169ms","remote":"127.0.0.1:50928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:34:13.613272Z","caller":"traceutil/trace.go:171","msg":"trace[1043113535] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"100.451837ms","start":"2026-04-16T16:34:13.512798Z","end":"2026-04-16T16:34:13.61325Z","steps":["trace[1043113535] 'process raft request' (duration: 100.374265ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:13.613425Z","caller":"traceutil/trace.go:171","msg":"trace[1985457260] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"105.962843ms","start":"2026-04-16T16:34:13.507333Z","end":"2026-04-16T16:34:13.613296Z","steps":["trace[1985457260] 'process raft request' (duration: 105.755598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:34:13.613506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.426323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T16:34:13.613532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.740806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:34:13.613556Z","caller":"traceutil/trace.go:171","msg":"trace[1283453500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"298.510635ms","start":"2026-04-16T16:34:13.315031Z","end":"2026-04-16T16:34:13.613541Z","steps":["trace[1283453500] 'agreement among raft nodes before linearized reading' (duration: 298.371562ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:13.61358Z","caller":"traceutil/trace.go:171","msg":"trace[624780502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"358.848879ms","start":"2026-04-16T16:34:13.254714Z","end":"2026-04-16T16:34:13.613563Z","steps":["trace[624780502] 'agreement among raft nodes before linearized reading' (duration: 358.681415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:34:13.613608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:34:13.254689Z","time spent":"358.91305ms","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:34:13.613621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.141106ms","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-04-16T16:34:13.613685Z","caller":"traceutil/trace.go:171","msg":"trace[1161063748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"138.209517ms","start":"2026-04-16T16:34:13.475458Z","end":"2026-04-16T16:34:13.613667Z","steps":["trace[1161063748] 'agreement among raft nodes before linearized reading' (duration: 137.962132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:34:13.613929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:34:13.218062Z","time spent":"395.118683ms","remote":"127.0.0.1:50928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:34:19.070305Z","caller":"traceutil/trace.go:171","msg":"trace[1860898020] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"177.040011ms","start":"2026-04-16T16:34:18.893241Z","end":"2026-04-16T16:34:19.070281Z","steps":["trace[1860898020] 'process raft request' (duration: 176.802646ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:20.675086Z","caller":"traceutil/trace.go:171","msg":"trace[1536151235] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"420.559503ms","start":"2026-04-16T16:34:20.254499Z","end":"2026-04-16T16:34:20.675058Z","steps":["trace[1536151235] 'read index received' (duration: 419.897888ms)","trace[1536151235] 'applied index is now lower than readState.Index' (duration: 660.655µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:34:20.67519Z","caller":"traceutil/trace.go:171","msg":"trace[13522609] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"511.125984ms","start":"2026-04-16T16:34:20.164041Z","end":"2026-04-16T16:34:20.675167Z","steps":["trace[13522609] 'process raft request' (duration: 510.512671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:34:20.675265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.748038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:34:20.675306Z","caller":"traceutil/trace.go:171","msg":"trace[1902861015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"420.84553ms","start":"2026-04-16T16:34:20.25445Z","end":"2026-04-16T16:34:20.675296Z","steps":["trace[1902861015] 'agreement among raft nodes before linearized reading' (duration: 420.737568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:34:20.675324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:34:20.164011Z","time spent":"511.236627ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:34:20.675345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:34:20.254422Z","time spent":"420.914132ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:34:20.675336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.421865ms","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-04-16T16:34:20.675394Z","caller":"traceutil/trace.go:171","msg":"trace[1446202732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"320.517817ms","start":"2026-04-16T16:34:20.354859Z","end":"2026-04-16T16:34:20.675376Z","steps":["trace[1446202732] 'agreement among raft nodes before linearized reading' (duration: 320.361013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:34:20.675439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:34:20.354835Z","time spent":"320.594668ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:34:20.675582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.497698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-72tt4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-16T16:34:20.675713Z","caller":"traceutil/trace.go:171","msg":"trace[364881643] range","detail":"{range_begin:/registry/pods/kube-system/cilium-72tt4; range_end:; response_count:1; response_revision:586; }","duration":"299.656051ms","start":"2026-04-16T16:34:20.376003Z","end":"2026-04-16T16:34:20.675659Z","steps":["trace[364881643] 'agreement among raft nodes before linearized reading' (duration: 299.317304ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:20.866456Z","caller":"traceutil/trace.go:171","msg":"trace[274021626] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"186.521357ms","start":"2026-04-16T16:34:20.679902Z","end":"2026-04-16T16:34:20.866424Z","steps":["trace[274021626] 'process raft request' (duration: 186.333582ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:20.875769Z","caller":"traceutil/trace.go:171","msg":"trace[520107509] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"184.441808ms","start":"2026-04-16T16:34:20.691288Z","end":"2026-04-16T16:34:20.875729Z","steps":["trace[520107509] 'process raft request' (duration: 184.173222ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:20.875915Z","caller":"traceutil/trace.go:171","msg":"trace[1157335549] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"138.242267ms","start":"2026-04-16T16:34:20.737642Z","end":"2026-04-16T16:34:20.875884Z","steps":["trace[1157335549] 'process raft request' (duration: 137.983921ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:34:37.105635Z","caller":"traceutil/trace.go:171","msg":"trace[876420633] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"163.842421ms","start":"2026-04-16T16:34:36.941769Z","end":"2026-04-16T16:34:37.105612Z","steps":["trace[876420633] 'process raft request' (duration: 163.664267ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:25.304876Z","caller":"traceutil/trace.go:171","msg":"trace[1202689077] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"100.200805ms","start":"2026-04-16T16:35:25.204641Z","end":"2026-04-16T16:35:25.304842Z","steps":["trace[1202689077] 'process raft request' (duration: 99.97754ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:27.144099Z","caller":"traceutil/trace.go:171","msg":"trace[297348748] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"122.042381ms","start":"2026-04-16T16:35:27.022032Z","end":"2026-04-16T16:35:27.144074Z","steps":["trace[297348748] 'process raft request' (duration: 121.832676ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:51.62695Z","caller":"traceutil/trace.go:171","msg":"trace[770945754] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"373.873786ms","start":"2026-04-16T16:35:51.253028Z","end":"2026-04-16T16:35:51.626902Z","steps":["trace[770945754] 'read index received' (duration: 353.178097ms)","trace[770945754] 'applied index is now lower than readState.Index' (duration: 20.693969ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:35:51.627007Z","caller":"traceutil/trace.go:171","msg":"trace[1857494376] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"396.848198ms","start":"2026-04-16T16:35:51.230112Z","end":"2026-04-16T16:35:51.62696Z","steps":["trace[1857494376] 'process raft request' (duration: 375.986525ms)","trace[1857494376] 'compare' (duration: 20.467554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:35:51.627132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.085652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:35:51.627286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.230087Z","time spent":"397.017612ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:35:51.627233Z","caller":"traceutil/trace.go:171","msg":"trace[966070933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1824; }","duration":"374.198214ms","start":"2026-04-16T16:35:51.253014Z","end":"2026-04-16T16:35:51.627212Z","steps":["trace[966070933] 'agreement among raft nodes before linearized reading' (duration: 374.041771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:51.627459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.252966Z","time spent":"374.433661ms","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:35:52.132616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.300161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:35:52.13321Z","caller":"traceutil/trace.go:171","msg":"trace[705040886] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"826.753828ms","start":"2026-04-16T16:35:51.306411Z","end":"2026-04-16T16:35:52.133165Z","steps":["trace[705040886] 'process raft request' (duration: 553.619388ms)","trace[705040886] 'compare' (duration: 272.153708ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:35:52.133252Z","caller":"traceutil/trace.go:171","msg":"trace[779161407] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1891; }","duration":"506.176111ms","start":"2026-04-16T16:35:51.627053Z","end":"2026-04-16T16:35:52.13323Z","steps":["trace[779161407] 'read index received' (duration: 232.966239ms)","trace[779161407] 'applied index is now lower than readState.Index' (duration: 273.208802ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:35:52.133326Z","caller":"traceutil/trace.go:171","msg":"trace[170516455] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"689.553943ms","start":"2026-04-16T16:35:51.443748Z","end":"2026-04-16T16:35:52.133302Z","steps":["trace[170516455] 'process raft request' (duration: 689.41051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:52.133433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.44373Z","time spent":"689.651135ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:35:52.133418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.306374Z","time spent":"826.928071ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:35:52.133534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.436978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:35:52.133606Z","caller":"traceutil/trace.go:171","msg":"trace[878572479] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1827; }","duration":"788.54061ms","start":"2026-04-16T16:35:51.345048Z","end":"2026-04-16T16:35:52.133588Z","steps":["trace[878572479] 'agreement among raft nodes before linearized reading' (duration: 788.352135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:52.133625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.549545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:35:52.133592Z","caller":"traceutil/trace.go:171","msg":"trace[494160716] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"797.694988ms","start":"2026-04-16T16:35:51.335837Z","end":"2026-04-16T16:35:52.133532Z","steps":["trace[494160716] 'process raft request' (duration: 797.226918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:52.133682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.833082ms","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-04-16T16:35:52.133737Z","caller":"traceutil/trace.go:171","msg":"trace[680423282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1827; }","duration":"638.942865ms","start":"2026-04-16T16:35:51.49478Z","end":"2026-04-16T16:35:52.133723Z","steps":["trace[680423282] 'agreement among raft nodes before linearized reading' (duration: 638.781211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:52.133784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.494748Z","time spent":"639.028017ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:35:52.133812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.702314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-16T16:35:52.133653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.047585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:35:52.133813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.765609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b37ef665-fbca-4c4d-b8b4-0b5adab547ba\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-16T16:35:52.133871Z","caller":"traceutil/trace.go:171","msg":"trace[78288358] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1827; }","duration":"396.789696ms","start":"2026-04-16T16:35:51.737059Z","end":"2026-04-16T16:35:52.133849Z","steps":["trace[78288358] 'agreement among raft nodes before linearized reading' (duration: 396.625633ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:52.133893Z","caller":"traceutil/trace.go:171","msg":"trace[100238310] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b37ef665-fbca-4c4d-b8b4-0b5adab547ba; range_end:; response_count:1; response_revision:1827; }","duration":"260.904132ms","start":"2026-04-16T16:35:51.872971Z","end":"2026-04-16T16:35:52.133875Z","steps":["trace[100238310] 'agreement among raft nodes before linearized reading' (duration: 260.673017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:52.133929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.737036Z","time spent":"396.881299ms","remote":"127.0.0.1:52926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-16T16:35:52.13391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.335822Z","time spent":"797.912643ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:35:52.133914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.899661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b37ef665-fbca-4c4d-b8b4-0b5adab547ba\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-16T16:35:52.133874Z","caller":"traceutil/trace.go:171","msg":"trace[916633797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1827; }","duration":"505.301831ms","start":"2026-04-16T16:35:51.628557Z","end":"2026-04-16T16:35:52.133859Z","steps":["trace[916633797] 'agreement among raft nodes before linearized reading' (duration: 505.057355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:52.133662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.345035Z","time spent":"788.614152ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":15,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:35:52.134057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.628536Z","time spent":"505.503895ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:35:52.133663Z","caller":"traceutil/trace.go:171","msg":"trace[14981849] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1827; }","duration":"723.631937ms","start":"2026-04-16T16:35:51.41002Z","end":"2026-04-16T16:35:52.133652Z","steps":["trace[14981849] 'agreement among raft nodes before linearized reading' (duration: 723.571306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:52.134252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.40999Z","time spent":"724.188979ms","remote":"127.0.0.1:49934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:35:52.134393Z","caller":"traceutil/trace.go:171","msg":"trace[335605217] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b37ef665-fbca-4c4d-b8b4-0b5adab547ba; range_end:; response_count:1; response_revision:1827; }","duration":"590.042034ms","start":"2026-04-16T16:35:51.543959Z","end":"2026-04-16T16:35:52.134001Z","steps":["trace[335605217] 'agreement among raft nodes before linearized reading' (duration: 589.396419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:35:52.134446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:35:51.543911Z","time spent":"590.523715ms","remote":"127.0.0.1:51030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b37ef665-fbca-4c4d-b8b4-0b5adab547ba\" "} {"level":"info","ts":"2026-04-16T16:35:52.276827Z","caller":"traceutil/trace.go:171","msg":"trace[508840526] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"137.467081ms","start":"2026-04-16T16:35:52.139328Z","end":"2026-04-16T16:35:52.276795Z","steps":["trace[508840526] 'process raft request' (duration: 131.511905ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:35:52.277797Z","caller":"traceutil/trace.go:171","msg":"trace[124601514] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"134.723239ms","start":"2026-04-16T16:35:52.143022Z","end":"2026-04-16T16:35:52.277745Z","steps":["trace[124601514] 'process raft request' (duration: 134.418612ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:07.60532Z","caller":"traceutil/trace.go:171","msg":"trace[573936040] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2030; }","duration":"353.866113ms","start":"2026-04-16T16:36:07.251428Z","end":"2026-04-16T16:36:07.605294Z","steps":["trace[573936040] 'read index received' (duration: 353.681618ms)","trace[573936040] 'applied index is now lower than readState.Index' (duration: 183.184µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:36:07.605349Z","caller":"traceutil/trace.go:171","msg":"trace[222393432] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"594.978925ms","start":"2026-04-16T16:36:07.010351Z","end":"2026-04-16T16:36:07.60533Z","steps":["trace[222393432] 'process raft request' (duration: 594.75738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:07.605535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:07.010332Z","time spent":"595.102169ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:36:07.60555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.112358ms","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-04-16T16:36:07.605639Z","caller":"traceutil/trace.go:171","msg":"trace[1549877111] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1961; }","duration":"354.236001ms","start":"2026-04-16T16:36:07.251376Z","end":"2026-04-16T16:36:07.605612Z","steps":["trace[1549877111] 'agreement among raft nodes before linearized reading' (duration: 354.017726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:07.605697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:07.251355Z","time spent":"354.331842ms","remote":"127.0.0.1:52926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-16T16:36:07.613901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.61119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:36:07.613987Z","caller":"traceutil/trace.go:171","msg":"trace[1483804141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1962; }","duration":"358.736723ms","start":"2026-04-16T16:36:07.255231Z","end":"2026-04-16T16:36:07.613968Z","steps":["trace[1483804141] 'agreement among raft nodes before linearized reading' (duration: 358.58898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:07.614029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:07.255211Z","time spent":"358.811025ms","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:36:36.208554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.119183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:36:36.208699Z","caller":"traceutil/trace.go:171","msg":"trace[2046842618] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"392.927775ms","start":"2026-04-16T16:36:35.815755Z","end":"2026-04-16T16:36:36.208683Z","steps":["trace[2046842618] 'read index received' (duration: 63.492474ms)","trace[2046842618] 'applied index is now lower than readState.Index' (duration: 329.434141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:36:36.208898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.141019ms","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-04-16T16:36:36.208947Z","caller":"traceutil/trace.go:171","msg":"trace[1059658437] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2124; }","duration":"393.222191ms","start":"2026-04-16T16:36:35.815713Z","end":"2026-04-16T16:36:36.208935Z","steps":["trace[1059658437] 'agreement among raft nodes before linearized reading' (duration: 393.021657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:36.208987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:35.815693Z","time spent":"393.285092ms","remote":"127.0.0.1:52926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-16T16:36:36.209272Z","caller":"traceutil/trace.go:171","msg":"trace[903471980] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"403.791621ms","start":"2026-04-16T16:36:35.805432Z","end":"2026-04-16T16:36:36.209224Z","steps":["trace[903471980] 'process raft request' (duration: 73.91194ms)","trace[903471980] 'compare' (duration: 328.926608ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:36:36.209565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:35.805395Z","time spent":"404.061987ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:36:36.536938Z","caller":"traceutil/trace.go:171","msg":"trace[948884135] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"246.676969ms","start":"2026-04-16T16:36:36.290234Z","end":"2026-04-16T16:36:36.536911Z","steps":["trace[948884135] 'process raft request' (duration: 246.401293ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:36.654425Z","caller":"traceutil/trace.go:171","msg":"trace[1919742096] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2200; }","duration":"149.96118ms","start":"2026-04-16T16:36:36.50442Z","end":"2026-04-16T16:36:36.654381Z","steps":["trace[1919742096] 'read index received' (duration: 32.269934ms)","trace[1919742096] 'applied index is now lower than readState.Index' (duration: 117.690156ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:36:36.654595Z","caller":"traceutil/trace.go:171","msg":"trace[132229126] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"362.597665ms","start":"2026-04-16T16:36:36.291934Z","end":"2026-04-16T16:36:36.654531Z","steps":["trace[132229126] 'process raft request' (duration: 306.460819ms)","trace[132229126] 'compare' (duration: 55.8603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:36:36.654766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.826744ms","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-04-16T16:36:36.654839Z","caller":"traceutil/trace.go:171","msg":"trace[1079592703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2126; }","duration":"148.922696ms","start":"2026-04-16T16:36:36.505899Z","end":"2026-04-16T16:36:36.654822Z","steps":["trace[1079592703] 'agreement among raft nodes before linearized reading' (duration: 148.765552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:36.654844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:36.291905Z","time spent":"362.809539ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:36:36.654975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.06768ms","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-04-16T16:36:36.655157Z","caller":"traceutil/trace.go:171","msg":"trace[1759567225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2126; }","duration":"120.304045ms","start":"2026-04-16T16:36:36.534824Z","end":"2026-04-16T16:36:36.655128Z","steps":["trace[1759567225] 'agreement among raft nodes before linearized reading' (duration: 119.891556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:36.655255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.634975ms","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-04-16T16:36:36.655414Z","caller":"traceutil/trace.go:171","msg":"trace[1186935366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2126; }","duration":"150.994383ms","start":"2026-04-16T16:36:36.504371Z","end":"2026-04-16T16:36:36.655366Z","steps":["trace[1186935366] 'agreement among raft nodes before linearized reading' (duration: 150.269177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:42.685244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.639311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:36:42.685339Z","caller":"traceutil/trace.go:171","msg":"trace[1631176568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2155; }","duration":"430.791315ms","start":"2026-04-16T16:36:42.254524Z","end":"2026-04-16T16:36:42.685315Z","steps":["trace[1631176568] 'agreement among raft nodes before linearized reading' (duration: 26.897532ms)","trace[1631176568] 'range keys from in-memory index tree' (duration: 403.73288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:36:42.685371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:42.254492Z","time spent":"430.872016ms","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:36:42.685476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.380795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-16T16:36:42.68556Z","caller":"traceutil/trace.go:171","msg":"trace[1366322001] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2155; }","duration":"207.538769ms","start":"2026-04-16T16:36:42.477998Z","end":"2026-04-16T16:36:42.685537Z","steps":["trace[1366322001] 'range keys from in-memory index tree' (duration: 207.175791ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:42.703311Z","caller":"traceutil/trace.go:171","msg":"trace[1392125841] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"144.437464ms","start":"2026-04-16T16:36:42.558839Z","end":"2026-04-16T16:36:42.703277Z","steps":["trace[1392125841] 'process raft request' (duration: 144.2645ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:42.863271Z","caller":"traceutil/trace.go:171","msg":"trace[150444732] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"188.27568ms","start":"2026-04-16T16:36:42.674968Z","end":"2026-04-16T16:36:42.863244Z","steps":["trace[150444732] 'process raft request' (duration: 160.536689ms)","trace[150444732] 'compare' (duration: 27.555047ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:36:42.863445Z","caller":"traceutil/trace.go:171","msg":"trace[1577741486] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2233; }","duration":"160.136901ms","start":"2026-04-16T16:36:42.703272Z","end":"2026-04-16T16:36:42.863409Z","steps":["trace[1577741486] 'read index received' (duration: 132.263677ms)","trace[1577741486] 'applied index is now lower than readState.Index' (duration: 27.865544ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:36:42.863519Z","caller":"traceutil/trace.go:171","msg":"trace[33755667] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"173.767191ms","start":"2026-04-16T16:36:42.689683Z","end":"2026-04-16T16:36:42.86345Z","steps":["trace[33755667] 'process raft request' (duration: 173.517185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:42.863697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.00931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:36:42.863698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.732957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:36:42.863969Z","caller":"traceutil/trace.go:171","msg":"trace[322189425] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2158; }","duration":"183.320628ms","start":"2026-04-16T16:36:42.68062Z","end":"2026-04-16T16:36:42.86394Z","steps":["trace[322189425] 'agreement among raft nodes before linearized reading' (duration: 183.000131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:42.863982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.300094ms","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-04-16T16:36:42.863976Z","caller":"traceutil/trace.go:171","msg":"trace[823889172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2158; }","duration":"177.058645ms","start":"2026-04-16T16:36:42.686887Z","end":"2026-04-16T16:36:42.863946Z","steps":["trace[823889172] 'agreement among raft nodes before linearized reading' (duration: 176.726147ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:42.864022Z","caller":"traceutil/trace.go:171","msg":"trace[219357736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2158; }","duration":"138.391546ms","start":"2026-04-16T16:36:42.725619Z","end":"2026-04-16T16:36:42.864011Z","steps":["trace[219357736] 'agreement among raft nodes before linearized reading' (duration: 138.062569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:42.863693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.188154ms","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-04-16T16:36:42.864124Z","caller":"traceutil/trace.go:171","msg":"trace[683798800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2158; }","duration":"168.669624ms","start":"2026-04-16T16:36:42.695427Z","end":"2026-04-16T16:36:42.864097Z","steps":["trace[683798800] 'agreement among raft nodes before linearized reading' (duration: 168.01712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:42.864238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.336653ms","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-04-16T16:36:42.864315Z","caller":"traceutil/trace.go:171","msg":"trace[1711390100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2158; }","duration":"137.475116ms","start":"2026-04-16T16:36:42.726822Z","end":"2026-04-16T16:36:42.864297Z","steps":["trace[1711390100] 'agreement among raft nodes before linearized reading' (duration: 137.180709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:43.301017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.477237ms","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-04-16T16:36:43.301091Z","caller":"traceutil/trace.go:171","msg":"trace[1646968331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2162; }","duration":"306.60757ms","start":"2026-04-16T16:36:42.994469Z","end":"2026-04-16T16:36:43.301077Z","steps":["trace[1646968331] 'range keys from in-memory index tree' (duration: 306.270532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:43.30114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:42.99445Z","time spent":"306.680352ms","remote":"127.0.0.1:51138","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":"info","ts":"2026-04-16T16:36:43.301078Z","caller":"traceutil/trace.go:171","msg":"trace[1178162102] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"216.90626ms","start":"2026-04-16T16:36:43.084141Z","end":"2026-04-16T16:36:43.301048Z","steps":["trace[1178162102] 'process raft request' (duration: 208.176662ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:44.968471Z","caller":"traceutil/trace.go:171","msg":"trace[1497225307] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"259.867469ms","start":"2026-04-16T16:36:44.708576Z","end":"2026-04-16T16:36:44.968443Z","steps":["trace[1497225307] 'process raft request' (duration: 259.675814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.611135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.881595ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-16T16:36:46.611738Z","caller":"traceutil/trace.go:171","msg":"trace[21629553] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"1.74215824s","start":"2026-04-16T16:36:44.869522Z","end":"2026-04-16T16:36:46.61168Z","steps":["trace[21629553] 'process raft request' (duration: 1.74172983s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.612254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:44.869487Z","time spent":"1.742660541s","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:36:46.634489Z","caller":"traceutil/trace.go:171","msg":"trace[1046615784] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"1.695419727s","start":"2026-04-16T16:36:44.93905Z","end":"2026-04-16T16:36:46.63447Z","steps":["trace[1046615784] 'process raft request' (duration: 1.672584328s)","trace[1046615784] 'compare' (duration: 22.610554ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:36:46.63458Z","caller":"traceutil/trace.go:171","msg":"trace[177066948] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"285.380278ms","start":"2026-04-16T16:36:46.34919Z","end":"2026-04-16T16:36:46.63457Z","steps":["trace[177066948] 'process raft request' (duration: 285.352658ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:46.634594Z","caller":"traceutil/trace.go:171","msg":"trace[23558660] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2243; }","duration":"1.751493112s","start":"2026-04-16T16:36:44.882974Z","end":"2026-04-16T16:36:46.634467Z","steps":["trace[23558660] 'read index received' (duration: 85.365052ms)","trace[23558660] 'applied index is now lower than readState.Index' (duration: 1.66612625s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:36:46.634633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:44.93903Z","time spent":"1.695512309s","remote":"127.0.0.1:51026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:36:46.634806Z","caller":"traceutil/trace.go:171","msg":"trace[89369846] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"824.219989ms","start":"2026-04-16T16:36:45.81058Z","end":"2026-04-16T16:36:46.6348Z","steps":["trace[89369846] 'process raft request' (duration: 823.933353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.634833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.75184016s","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-04-16T16:36:46.634862Z","caller":"traceutil/trace.go:171","msg":"trace[1003602246] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"1.485103546s","start":"2026-04-16T16:36:45.149755Z","end":"2026-04-16T16:36:46.634859Z","steps":["trace[1003602246] 'process raft request' (duration: 1.484668546s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:46.634871Z","caller":"traceutil/trace.go:171","msg":"trace[1329815012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2173; }","duration":"1.751924592s","start":"2026-04-16T16:36:44.882935Z","end":"2026-04-16T16:36:46.63486Z","steps":["trace[1329815012] 'agreement among raft nodes before linearized reading' (duration: 1.751769198s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.634845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:45.810538Z","time spent":"824.288931ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:36:46.634959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:44.882909Z","time spent":"1.752041434s","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T16:36:46.634903Z","caller":"traceutil/trace.go:171","msg":"trace[177283182] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"1.283185225s","start":"2026-04-16T16:36:45.351714Z","end":"2026-04-16T16:36:46.634899Z","steps":["trace[177283182] 'process raft request' (duration: 1.282752195s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.634887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:45.149727Z","time spent":"1.485149706s","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:36:46.635059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305153843s","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-04-16T16:36:46.6351Z","caller":"traceutil/trace.go:171","msg":"trace[1906963618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2173; }","duration":"1.305252785s","start":"2026-04-16T16:36:45.329839Z","end":"2026-04-16T16:36:46.635092Z","steps":["trace[1906963618] 'agreement among raft nodes before linearized reading' (duration: 1.305137892s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.635135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:45.329786Z","time spent":"1.305339109s","remote":"127.0.0.1:51138","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-04-16T16:36:46.635172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496341992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a6e39feefd2f4f\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-04-16T16:36:46.635038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:45.351677Z","time spent":"1.283345708s","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:36:46.635189Z","caller":"traceutil/trace.go:171","msg":"trace[983137479] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a6e39feefd2f4f; range_end:; response_count:1; response_revision:2173; }","duration":"1.496411013s","start":"2026-04-16T16:36:45.138772Z","end":"2026-04-16T16:36:46.635183Z","steps":["trace[983137479] 'agreement among raft nodes before linearized reading' (duration: 1.496352172s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.635225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:45.13875Z","time spent":"1.496471585s","remote":"127.0.0.1:50946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a6e39feefd2f4f\" "} {"level":"warn","ts":"2026-04-16T16:36:46.635207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283102964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-16T16:36:46.635311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750578882s","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-04-16T16:36:46.635319Z","caller":"traceutil/trace.go:171","msg":"trace[1196607486] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"1.283270837s","start":"2026-04-16T16:36:45.352019Z","end":"2026-04-16T16:36:46.63529Z","steps":["trace[1196607486] 'agreement among raft nodes before linearized reading' (duration: 1.282933239s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:46.635342Z","caller":"traceutil/trace.go:171","msg":"trace[463537511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2173; }","duration":"1.750711434s","start":"2026-04-16T16:36:44.884623Z","end":"2026-04-16T16:36:46.635335Z","steps":["trace[463537511] 'agreement among raft nodes before linearized reading' (duration: 1.750641413s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.635374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:45.351991Z","time spent":"1.28337153s","remote":"127.0.0.1:52926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-16T16:36:46.635391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742511167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-16T16:36:46.635405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742588s","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-04-16T16:36:46.635427Z","caller":"traceutil/trace.go:171","msg":"trace[2105366411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2173; }","duration":"1.74262758s","start":"2026-04-16T16:36:44.892794Z","end":"2026-04-16T16:36:46.635422Z","steps":["trace[2105366411] 'agreement among raft nodes before linearized reading' (duration: 1.742572749s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.635443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:44.892784Z","time spent":"1.742656181s","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:36:46.635526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380564278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:36:46.63543Z","caller":"traceutil/trace.go:171","msg":"trace[1992434552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"1.742570679s","start":"2026-04-16T16:36:44.892849Z","end":"2026-04-16T16:36:46.63542Z","steps":["trace[1992434552] 'agreement among raft nodes before linearized reading' (duration: 1.742459967s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:36:46.63556Z","caller":"traceutil/trace.go:171","msg":"trace[1278638870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2173; }","duration":"1.380651701s","start":"2026-04-16T16:36:45.2549Z","end":"2026-04-16T16:36:46.635551Z","steps":["trace[1278638870] 'agreement among raft nodes before linearized reading' (duration: 1.38059877s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.635568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:44.892843Z","time spent":"1.742717142s","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:36:46.635582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:45.254685Z","time spent":"1.380892316s","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:36:46.635574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61411559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-04-16T16:36:46.635669Z","caller":"traceutil/trace.go:171","msg":"trace[1647240077] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2173; }","duration":"1.614258442s","start":"2026-04-16T16:36:45.02139Z","end":"2026-04-16T16:36:46.635648Z","steps":["trace[1647240077] 'agreement among raft nodes before linearized reading' (duration: 1.613896094s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:46.635713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:45.021371Z","time spent":"1.614334115s","remote":"127.0.0.1:51030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12410,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-16T16:36:46.635363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:44.884603Z","time spent":"1.750754645s","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:37:43.313053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014850865294568593,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T16:37:43.449763Z","caller":"traceutil/trace.go:171","msg":"trace[681586431] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"639.324253ms","start":"2026-04-16T16:37:42.810407Z","end":"2026-04-16T16:37:43.449731Z","steps":["trace[681586431] 'process raft request' (duration: 639.122248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:37:43.449951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.810387Z","time spent":"639.477267ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:37:43.468525Z","caller":"traceutil/trace.go:171","msg":"trace[1101158222] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"636.368756ms","start":"2026-04-16T16:37:42.83213Z","end":"2026-04-16T16:37:43.468499Z","steps":["trace[1101158222] 'process raft request' (duration: 636.289304ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:37:43.468649Z","caller":"traceutil/trace.go:171","msg":"trace[619138223] transaction","detail":"{read_only:false; response_revision:2643; number_of_response:1; }","duration":"636.57291ms","start":"2026-04-16T16:37:42.832058Z","end":"2026-04-16T16:37:43.468631Z","steps":["trace[619138223] 'process raft request' (duration: 636.167901ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:37:43.468639Z","caller":"traceutil/trace.go:171","msg":"trace[48574103] linearizableReadLoop","detail":"{readStateIndex:2742; appliedIndex:2738; }","duration":"655.91968ms","start":"2026-04-16T16:37:42.812688Z","end":"2026-04-16T16:37:43.468608Z","steps":["trace[48574103] 'read index received' (duration: 636.947879ms)","trace[48574103] 'applied index is now lower than readState.Index' (duration: 18.969761ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:37:43.468705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.832123Z","time spent":"636.482578ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:37:43.468735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.832039Z","time spent":"636.648972ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:37:43.468794Z","caller":"traceutil/trace.go:171","msg":"trace[2073638687] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"289.754167ms","start":"2026-04-16T16:37:43.17901Z","end":"2026-04-16T16:37:43.468765Z","steps":["trace[2073638687] 'process raft request' (duration: 289.447491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:37:43.468883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.371597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-16T16:37:43.46891Z","caller":"traceutil/trace.go:171","msg":"trace[1249352350] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2645; }","duration":"566.435859ms","start":"2026-04-16T16:37:42.902467Z","end":"2026-04-16T16:37:43.468903Z","steps":["trace[1249352350] 'agreement among raft nodes before linearized reading' (duration: 566.344097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:37:43.46893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.902447Z","time spent":"566.479229ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-16T16:37:43.469025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.321829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-16T16:37:43.469072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.056117ms","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-04-16T16:37:43.469092Z","caller":"traceutil/trace.go:171","msg":"trace[586665792] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2645; }","duration":"656.40155ms","start":"2026-04-16T16:37:42.812674Z","end":"2026-04-16T16:37:43.469075Z","steps":["trace[586665792] 'agreement among raft nodes before linearized reading' (duration: 656.084533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:37:43.469098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.610357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-16T16:37:43.469141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.812627Z","time spent":"656.501603ms","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-16T16:37:43.469192Z","caller":"traceutil/trace.go:171","msg":"trace[2134091421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2645; }","duration":"542.719869ms","start":"2026-04-16T16:37:42.926449Z","end":"2026-04-16T16:37:43.469169Z","steps":["trace[2134091421] 'agreement among raft nodes before linearized reading' (duration: 542.445633ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:37:43.469136Z","caller":"traceutil/trace.go:171","msg":"trace[432261399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2645; }","duration":"543.129578ms","start":"2026-04-16T16:37:42.925991Z","end":"2026-04-16T16:37:43.469121Z","steps":["trace[432261399] 'agreement among raft nodes before linearized reading' (duration: 542.969795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:37:43.46923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.889496ms","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":"warn","ts":"2026-04-16T16:37:43.469241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.926407Z","time spent":"542.825852ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:37:43.469267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.925987Z","time spent":"543.274792ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T16:37:43.469285Z","caller":"traceutil/trace.go:171","msg":"trace[1503457967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2645; }","duration":"543.945568ms","start":"2026-04-16T16:37:42.925323Z","end":"2026-04-16T16:37:43.469269Z","steps":["trace[1503457967] 'agreement among raft nodes before linearized reading' (duration: 543.635871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:37:43.469337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.925285Z","time spent":"544.04381ms","remote":"127.0.0.1:51138","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-04-16T16:37:43.469085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.026728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-16T16:37:43.469436Z","caller":"traceutil/trace.go:171","msg":"trace[1362868551] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2645; }","duration":"229.399317ms","start":"2026-04-16T16:37:43.240023Z","end":"2026-04-16T16:37:43.469422Z","steps":["trace[1362868551] 'agreement among raft nodes before linearized reading' (duration: 228.888095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:37:43.469063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.119886ms","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-04-16T16:37:43.469562Z","caller":"traceutil/trace.go:171","msg":"trace[1722630437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2645; }","duration":"542.608248ms","start":"2026-04-16T16:37:42.926926Z","end":"2026-04-16T16:37:43.469534Z","steps":["trace[1722630437] 'agreement among raft nodes before linearized reading' (duration: 541.975943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:37:43.469655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.926863Z","time spent":"542.772162ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:37:43.469218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.0167ms","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-04-16T16:37:43.469718Z","caller":"traceutil/trace.go:171","msg":"trace[559173320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2645; }","duration":"548.576473ms","start":"2026-04-16T16:37:42.921125Z","end":"2026-04-16T16:37:43.469702Z","steps":["trace[559173320] 'agreement among raft nodes before linearized reading' (duration: 547.853426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:37:43.469814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:37:42.921102Z","time spent":"548.696476ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T16:38:16.92808Z","caller":"traceutil/trace.go:171","msg":"trace[1312599280] linearizableReadLoop","detail":"{readStateIndex:2952; appliedIndex:2951; }","duration":"115.539493ms","start":"2026-04-16T16:38:16.812513Z","end":"2026-04-16T16:38:16.928053Z","steps":["trace[1312599280] 'read index received' (duration: 115.276527ms)","trace[1312599280] 'applied index is now lower than readState.Index' (duration: 257.826µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:38:16.928156Z","caller":"traceutil/trace.go:171","msg":"trace[1030618939] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"203.55172ms","start":"2026-04-16T16:38:16.724561Z","end":"2026-04-16T16:38:16.928112Z","steps":["trace[1030618939] 'process raft request' (duration: 203.296734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:16.928331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.800398ms","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-04-16T16:38:16.928378Z","caller":"traceutil/trace.go:171","msg":"trace[709700823] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2847; }","duration":"115.920751ms","start":"2026-04-16T16:38:16.812446Z","end":"2026-04-16T16:38:16.928366Z","steps":["trace[709700823] 'agreement among raft nodes before linearized reading' (duration: 115.724946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:17.58717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.389833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:38:17.587233Z","caller":"traceutil/trace.go:171","msg":"trace[1610730383] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2847; }","duration":"287.500116ms","start":"2026-04-16T16:38:17.299715Z","end":"2026-04-16T16:38:17.587215Z","steps":["trace[1610730383] 'count revisions from in-memory index tree' (duration: 287.313361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:17.587793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.419687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:38:17.587849Z","caller":"traceutil/trace.go:171","msg":"trace[594775527] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2847; }","duration":"421.513059ms","start":"2026-04-16T16:38:17.166319Z","end":"2026-04-16T16:38:17.587832Z","steps":["trace[594775527] 'count revisions from in-memory index tree' (duration: 421.320895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:17.587895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:38:17.166296Z","time spent":"421.585591ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:38:17.883252Z","caller":"traceutil/trace.go:171","msg":"trace[1984743397] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"289.43761ms","start":"2026-04-16T16:38:17.593777Z","end":"2026-04-16T16:38:17.883215Z","steps":["trace[1984743397] 'process raft request' (duration: 289.141084ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:17.883309Z","caller":"traceutil/trace.go:171","msg":"trace[937806094] linearizableReadLoop","detail":"{readStateIndex:2956; appliedIndex:2952; }","duration":"288.151552ms","start":"2026-04-16T16:38:17.595121Z","end":"2026-04-16T16:38:17.883273Z","steps":["trace[937806094] 'read index received' (duration: 12.7336ms)","trace[937806094] 'applied index is now lower than readState.Index' (duration: 275.416722ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:38:17.883449Z","caller":"traceutil/trace.go:171","msg":"trace[2114752281] transaction","detail":"{read_only:false; response_revision:2851; number_of_response:1; }","duration":"288.461888ms","start":"2026-04-16T16:38:17.594895Z","end":"2026-04-16T16:38:17.883357Z","steps":["trace[2114752281] 'process raft request' (duration: 288.287835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:17.883696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.233344ms","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-04-16T16:38:17.883723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.774618ms","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-04-16T16:38:17.883764Z","caller":"traceutil/trace.go:171","msg":"trace[147945832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2851; }","duration":"244.404868ms","start":"2026-04-16T16:38:17.639338Z","end":"2026-04-16T16:38:17.883743Z","steps":["trace[147945832] 'agreement among raft nodes before linearized reading' (duration: 244.204554ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:17.883804Z","caller":"traceutil/trace.go:171","msg":"trace[896971812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2851; }","duration":"241.979413ms","start":"2026-04-16T16:38:17.641803Z","end":"2026-04-16T16:38:17.883783Z","steps":["trace[896971812] 'agreement among raft nodes before linearized reading' (duration: 241.743738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:17.883736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.586421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a6e3b6dd831695\" ","response":"range_response_count:1 size:765"} {"level":"warn","ts":"2026-04-16T16:38:17.883839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.801439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T16:38:17.883879Z","caller":"traceutil/trace.go:171","msg":"trace[1252510633] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a6e3b6dd831695; range_end:; response_count:1; response_revision:2851; }","duration":"288.756594ms","start":"2026-04-16T16:38:17.595092Z","end":"2026-04-16T16:38:17.883849Z","steps":["trace[1252510633] 'agreement among raft nodes before linearized reading' (duration: 288.345095ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:17.883891Z","caller":"traceutil/trace.go:171","msg":"trace[246027511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2851; }","duration":"241.86951ms","start":"2026-04-16T16:38:17.642004Z","end":"2026-04-16T16:38:17.883873Z","steps":["trace[246027511] 'agreement among raft nodes before linearized reading' (duration: 241.704316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:17.883863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.033736ms","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-04-16T16:38:17.883981Z","caller":"traceutil/trace.go:171","msg":"trace[1891894238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2851; }","duration":"243.20915ms","start":"2026-04-16T16:38:17.64074Z","end":"2026-04-16T16:38:17.88395Z","steps":["trace[1891894238] 'agreement among raft nodes before linearized reading' (duration: 242.897703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:17.884301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.939798ms","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-04-16T16:38:17.884368Z","caller":"traceutil/trace.go:171","msg":"trace[495792104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2851; }","duration":"244.069642ms","start":"2026-04-16T16:38:17.640284Z","end":"2026-04-16T16:38:17.884353Z","steps":["trace[495792104] 'agreement among raft nodes before linearized reading' (duration: 243.654701ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:17.885703Z","caller":"traceutil/trace.go:171","msg":"trace[195135871] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"289.240145ms","start":"2026-04-16T16:38:17.594075Z","end":"2026-04-16T16:38:17.883315Z","steps":["trace[195135871] 'process raft request' (duration: 289.038551ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:23.784089Z","caller":"traceutil/trace.go:171","msg":"trace[1173272651] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"154.563452ms","start":"2026-04-16T16:38:23.629502Z","end":"2026-04-16T16:38:23.784065Z","steps":["trace[1173272651] 'process raft request' (duration: 154.423448ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:24.016833Z","caller":"traceutil/trace.go:171","msg":"trace[1252473696] linearizableReadLoop","detail":"{readStateIndex:2990; appliedIndex:2987; }","duration":"102.069812ms","start":"2026-04-16T16:38:23.914732Z","end":"2026-04-16T16:38:24.016801Z","steps":["trace[1252473696] 'read index received' (duration: 44.076649ms)","trace[1252473696] 'applied index is now lower than readState.Index' (duration: 57.991783ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:38:24.016901Z","caller":"traceutil/trace.go:171","msg":"trace[489097676] transaction","detail":"{read_only:false; response_revision:2883; number_of_response:1; }","duration":"109.228455ms","start":"2026-04-16T16:38:23.907626Z","end":"2026-04-16T16:38:24.016855Z","steps":["trace[489097676] 'process raft request' (duration: 109.01071ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:24.016985Z","caller":"traceutil/trace.go:171","msg":"trace[2117948417] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"107.66997ms","start":"2026-04-16T16:38:23.909299Z","end":"2026-04-16T16:38:24.016969Z","steps":["trace[2117948417] 'process raft request' (duration: 107.454705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:24.017197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.024269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T16:38:24.017268Z","caller":"traceutil/trace.go:171","msg":"trace[105248802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2884; }","duration":"101.136301ms","start":"2026-04-16T16:38:23.916117Z","end":"2026-04-16T16:38:24.017253Z","steps":["trace[105248802] 'agreement among raft nodes before linearized reading' (duration: 100.928057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:38:24.017476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.715188ms","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-04-16T16:38:24.017662Z","caller":"traceutil/trace.go:171","msg":"trace[975355439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2884; }","duration":"102.939192ms","start":"2026-04-16T16:38:23.914704Z","end":"2026-04-16T16:38:24.017643Z","steps":["trace[975355439] 'agreement among raft nodes before linearized reading' (duration: 102.459961ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:24.130737Z","caller":"traceutil/trace.go:171","msg":"trace[756063474] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"108.792845ms","start":"2026-04-16T16:38:24.021919Z","end":"2026-04-16T16:38:24.130712Z","steps":["trace[756063474] 'process raft request' (duration: 108.442457ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:24.131152Z","caller":"traceutil/trace.go:171","msg":"trace[162773835] transaction","detail":"{read_only:false; response_revision:2889; number_of_response:1; }","duration":"107.981545ms","start":"2026-04-16T16:38:24.023142Z","end":"2026-04-16T16:38:24.131124Z","steps":["trace[162773835] 'process raft request' (duration: 107.664988ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:38:24.131184Z","caller":"traceutil/trace.go:171","msg":"trace[248923515] transaction","detail":"{read_only:false; response_revision:2886; number_of_response:1; }","duration":"109.791797ms","start":"2026-04-16T16:38:24.021335Z","end":"2026-04-16T16:38:24.131126Z","steps":["trace[248923515] 'process raft request' (duration: 76.021102ms)","trace[248923515] 'compare' (duration: 32.861715ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:38:24.13154Z","caller":"traceutil/trace.go:171","msg":"trace[504302653] transaction","detail":"{read_only:false; response_revision:2888; number_of_response:1; }","duration":"108.567258ms","start":"2026-04-16T16:38:24.022491Z","end":"2026-04-16T16:38:24.131058Z","steps":["trace[504302653] 'process raft request' (duration: 108.169309ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:40:26.581125Z","caller":"traceutil/trace.go:171","msg":"trace[623478866] transaction","detail":"{read_only:false; response_revision:3649; number_of_response:1; }","duration":"142.427038ms","start":"2026-04-16T16:40:26.438673Z","end":"2026-04-16T16:40:26.5811Z","steps":["trace[623478866] 'process raft request' (duration: 142.277005ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:40:26.680988Z","caller":"traceutil/trace.go:171","msg":"trace[633300533] transaction","detail":"{read_only:false; response_revision:3650; number_of_response:1; }","duration":"210.649115ms","start":"2026-04-16T16:40:26.470315Z","end":"2026-04-16T16:40:26.680964Z","steps":["trace[633300533] 'process raft request' (duration: 210.214305ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:40:26.681123Z","caller":"traceutil/trace.go:171","msg":"trace[2109299638] transaction","detail":"{read_only:false; response_revision:3651; number_of_response:1; }","duration":"210.706866ms","start":"2026-04-16T16:40:26.470387Z","end":"2026-04-16T16:40:26.681094Z","steps":["trace[2109299638] 'process raft request' (duration: 210.295647ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:41:00.031825Z","caller":"traceutil/trace.go:171","msg":"trace[2093121844] transaction","detail":"{read_only:false; response_revision:3907; number_of_response:1; }","duration":"114.609469ms","start":"2026-04-16T16:40:59.917186Z","end":"2026-04-16T16:41:00.031796Z","steps":["trace[2093121844] 'process raft request' (duration: 79.674121ms)","trace[2093121844] 'compare' (duration: 34.811776ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:42:15.905762Z","caller":"traceutil/trace.go:171","msg":"trace[27856787] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5003; }","duration":"122.589493ms","start":"2026-04-16T16:42:15.783146Z","end":"2026-04-16T16:42:15.905736Z","steps":["trace[27856787] 'read index received' (duration: 65.774567ms)","trace[27856787] 'applied index is now lower than readState.Index' (duration: 56.814016ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:42:15.905884Z","caller":"traceutil/trace.go:171","msg":"trace[470591374] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"123.77619ms","start":"2026-04-16T16:42:15.782072Z","end":"2026-04-16T16:42:15.905848Z","steps":["trace[470591374] 'process raft request' (duration: 66.910493ms)","trace[470591374] 'compare' (duration: 56.641641ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:42:15.905944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.777877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-16T16:42:15.906021Z","caller":"traceutil/trace.go:171","msg":"trace[851046837] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4823; }","duration":"122.87385ms","start":"2026-04-16T16:42:15.783129Z","end":"2026-04-16T16:42:15.906003Z","steps":["trace[851046837] 'agreement among raft nodes before linearized reading' (duration: 122.693466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:15.90614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.289366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:10 size:76036"} {"level":"info","ts":"2026-04-16T16:42:15.906181Z","caller":"traceutil/trace.go:171","msg":"trace[1770056230] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:10; response_revision:4823; }","duration":"122.391648ms","start":"2026-04-16T16:42:15.783778Z","end":"2026-04-16T16:42:15.906169Z","steps":["trace[1770056230] 'agreement among raft nodes before linearized reading' (duration: 122.116442ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:17.11631Z","caller":"traceutil/trace.go:171","msg":"trace[805819934] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"127.293759ms","start":"2026-04-16T16:42:16.98899Z","end":"2026-04-16T16:42:17.116284Z","steps":["trace[805819934] 'process raft request' (duration: 127.133166ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:17.184293Z","caller":"traceutil/trace.go:171","msg":"trace[1917000661] linearizableReadLoop","detail":"{readStateIndex:5023; appliedIndex:5020; }","duration":"124.2354ms","start":"2026-04-16T16:42:17.060043Z","end":"2026-04-16T16:42:17.184279Z","steps":["trace[1917000661] 'read index received' (duration: 56.101179ms)","trace[1917000661] 'applied index is now lower than readState.Index' (duration: 68.133831ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:42:17.184363Z","caller":"traceutil/trace.go:171","msg":"trace[500114904] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"139.73287ms","start":"2026-04-16T16:42:17.044622Z","end":"2026-04-16T16:42:17.184355Z","steps":["trace[500114904] 'process raft request' (duration: 124.478146ms)","trace[500114904] 'compare' (duration: 15.06117ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:42:17.184464Z","caller":"traceutil/trace.go:171","msg":"trace[355765412] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"138.797469ms","start":"2026-04-16T16:42:17.045658Z","end":"2026-04-16T16:42:17.184455Z","steps":["trace[355765412] 'process raft request' (duration: 138.582115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:17.184608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.519416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:42:17.184644Z","caller":"traceutil/trace.go:171","msg":"trace[1668028851] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4842; }","duration":"124.627377ms","start":"2026-04-16T16:42:17.060007Z","end":"2026-04-16T16:42:17.184635Z","steps":["trace[1668028851] 'agreement among raft nodes before linearized reading' (duration: 124.529495ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:21.319269Z","caller":"traceutil/trace.go:171","msg":"trace[650249583] linearizableReadLoop","detail":"{readStateIndex:5131; appliedIndex:5129; }","duration":"107.8991ms","start":"2026-04-16T16:42:21.21135Z","end":"2026-04-16T16:42:21.319249Z","steps":["trace[650249583] 'read index received' (duration: 888.39µs)","trace[650249583] 'applied index is now lower than readState.Index' (duration: 107.00944ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:42:21.319373Z","caller":"traceutil/trace.go:171","msg":"trace[635795069] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"124.222599ms","start":"2026-04-16T16:42:21.195143Z","end":"2026-04-16T16:42:21.319365Z","steps":["trace[635795069] 'process raft request' (duration: 77.44379ms)","trace[635795069] 'compare' (duration: 46.451491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:42:21.319775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.408912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-16T16:42:21.319881Z","caller":"traceutil/trace.go:171","msg":"trace[145975779] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4945; }","duration":"108.560666ms","start":"2026-04-16T16:42:21.211296Z","end":"2026-04-16T16:42:21.319857Z","steps":["trace[145975779] 'agreement among raft nodes before linearized reading' (duration: 108.29691ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:24.883064Z","caller":"traceutil/trace.go:171","msg":"trace[434819975] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5069; }","duration":"172.954151ms","start":"2026-04-16T16:42:24.71009Z","end":"2026-04-16T16:42:24.883044Z","steps":["trace[434819975] 'process raft request' (duration: 154.74633ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:25.173604Z","caller":"traceutil/trace.go:171","msg":"trace[1013856070] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"283.138083ms","start":"2026-04-16T16:42:24.89044Z","end":"2026-04-16T16:42:25.173578Z","steps":["trace[1013856070] 'process raft request' (duration: 282.970439ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:25.207956Z","caller":"traceutil/trace.go:171","msg":"trace[1476237155] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"308.277962ms","start":"2026-04-16T16:42:24.899656Z","end":"2026-04-16T16:42:25.207934Z","steps":["trace[1476237155] 'process raft request' (duration: 305.043899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:25.20808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:42:24.899635Z","time spent":"308.384744ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58198,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:42:42.49791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.471708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-04-16T16:42:42.49801Z","caller":"traceutil/trace.go:171","msg":"trace[1312683353] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5418; }","duration":"160.614742ms","start":"2026-04-16T16:42:42.337374Z","end":"2026-04-16T16:42:42.497988Z","steps":["trace[1312683353] 'range keys from in-memory index tree' (duration: 160.395056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:42.498023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.341173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-16T16:42:42.498123Z","caller":"traceutil/trace.go:171","msg":"trace[69669115] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5418; }","duration":"163.478746ms","start":"2026-04-16T16:42:42.334619Z","end":"2026-04-16T16:42:42.498098Z","steps":["trace[69669115] 'range keys from in-memory index tree' (duration: 163.20011ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:43.239336Z","caller":"traceutil/trace.go:171","msg":"trace[1192415439] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"169.592585ms","start":"2026-04-16T16:42:43.069709Z","end":"2026-04-16T16:42:43.239302Z","steps":["trace[1192415439] 'process raft request' (duration: 107.978392ms)","trace[1192415439] 'compare' (duration: 61.45615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:42:43.633381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.788743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:42:43.633579Z","caller":"traceutil/trace.go:171","msg":"trace[2039170247] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"303.37714ms","start":"2026-04-16T16:42:43.330178Z","end":"2026-04-16T16:42:43.633555Z","steps":["trace[2039170247] 'process raft request' (duration: 303.309018ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:43.633614Z","caller":"traceutil/trace.go:171","msg":"trace[778427213] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"325.47058ms","start":"2026-04-16T16:42:43.308096Z","end":"2026-04-16T16:42:43.633567Z","steps":["trace[778427213] 'process raft request' (duration: 81.413691ms)","trace[778427213] 'compare' (duration: 243.66456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:42:43.633686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:42:43.330156Z","time spent":"303.483073ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:42:43.633785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:42:43.308063Z","time spent":"325.632084ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:42:43.670162Z","caller":"traceutil/trace.go:171","msg":"trace[2095412961] linearizableReadLoop","detail":"{readStateIndex:5631; appliedIndex:5628; }","duration":"230.046063ms","start":"2026-04-16T16:42:43.440091Z","end":"2026-04-16T16:42:43.670137Z","steps":["trace[2095412961] 'read index received' (duration: 74.238589ms)","trace[2095412961] 'applied index is now lower than readState.Index' (duration: 155.806574ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:42:43.670272Z","caller":"traceutil/trace.go:171","msg":"trace[768454097] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"245.483981ms","start":"2026-04-16T16:42:43.424774Z","end":"2026-04-16T16:42:43.670258Z","steps":["trace[768454097] 'process raft request' (duration: 245.257825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:43.670468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.911774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T16:42:43.670553Z","caller":"traceutil/trace.go:171","msg":"trace[755415775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5433; }","duration":"197.039617ms","start":"2026-04-16T16:42:43.473494Z","end":"2026-04-16T16:42:43.670533Z","steps":["trace[755415775] 'agreement among raft nodes before linearized reading' (duration: 196.839832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:43.670468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.356969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:42:43.670693Z","caller":"traceutil/trace.go:171","msg":"trace[1801912026] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5433; }","duration":"230.642316ms","start":"2026-04-16T16:42:43.440038Z","end":"2026-04-16T16:42:43.67068Z","steps":["trace[1801912026] 'agreement among raft nodes before linearized reading' (duration: 230.354669ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:45.620928Z","caller":"traceutil/trace.go:171","msg":"trace[2055375060] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"387.228846ms","start":"2026-04-16T16:42:45.233658Z","end":"2026-04-16T16:42:45.620887Z","steps":["trace[2055375060] 'process raft request' (duration: 361.169377ms)","trace[2055375060] 'compare' (duration: 25.853685ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:42:45.620915Z","caller":"traceutil/trace.go:171","msg":"trace[1136925269] linearizableReadLoop","detail":"{readStateIndex:5651; appliedIndex:5650; }","duration":"365.423874ms","start":"2026-04-16T16:42:45.255454Z","end":"2026-04-16T16:42:45.620878Z","steps":["trace[1136925269] 'read index received' (duration: 339.307384ms)","trace[1136925269] 'applied index is now lower than readState.Index' (duration: 26.11507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:42:45.621088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:42:45.233632Z","time spent":"387.390489ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1113,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:42:45.621153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.684229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:42:45.621229Z","caller":"traceutil/trace.go:171","msg":"trace[1420253788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5452; }","duration":"365.821783ms","start":"2026-04-16T16:42:45.255391Z","end":"2026-04-16T16:42:45.621213Z","steps":["trace[1420253788] 'agreement among raft nodes before linearized reading' (duration: 365.611078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:45.621284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:42:45.255369Z","time spent":"365.903615ms","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:42:45.6213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.129799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e8964629-0551-4b67-a653-f1563964f3e1\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-04-16T16:42:45.621355Z","caller":"traceutil/trace.go:171","msg":"trace[2060081350] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e8964629-0551-4b67-a653-f1563964f3e1; range_end:; response_count:1; response_revision:5452; }","duration":"339.21053ms","start":"2026-04-16T16:42:45.282129Z","end":"2026-04-16T16:42:45.621339Z","steps":["trace[2060081350] 'agreement among raft nodes before linearized reading' (duration: 339.039217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:45.621395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:42:45.282117Z","time spent":"339.270281ms","remote":"127.0.0.1:51030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3973,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e8964629-0551-4b67-a653-f1563964f3e1\" "} {"level":"info","ts":"2026-04-16T16:42:53.4194Z","caller":"traceutil/trace.go:171","msg":"trace[1533456460] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"139.866383ms","start":"2026-04-16T16:42:53.279513Z","end":"2026-04-16T16:42:53.419379Z","steps":["trace[1533456460] 'process raft request' (duration: 139.715599ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:59.961545Z","caller":"traceutil/trace.go:171","msg":"trace[31975918] linearizableReadLoop","detail":"{readStateIndex:5875; appliedIndex:5874; }","duration":"243.21329ms","start":"2026-04-16T16:42:59.718305Z","end":"2026-04-16T16:42:59.961518Z","steps":["trace[31975918] 'read index received' (duration: 221.054769ms)","trace[31975918] 'applied index is now lower than readState.Index' (duration: 22.157301ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:42:59.961636Z","caller":"traceutil/trace.go:171","msg":"trace[387371285] transaction","detail":"{read_only:false; response_revision:5670; number_of_response:1; }","duration":"246.77004ms","start":"2026-04-16T16:42:59.714823Z","end":"2026-04-16T16:42:59.961593Z","steps":["trace[387371285] 'process raft request' (duration: 224.589108ms)","trace[387371285] 'compare' (duration: 21.822224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:42:59.961721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.002605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:42:59.961757Z","caller":"traceutil/trace.go:171","msg":"trace[533410662] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5670; }","duration":"243.086227ms","start":"2026-04-16T16:42:59.718659Z","end":"2026-04-16T16:42:59.961745Z","steps":["trace[533410662] 'agreement among raft nodes before linearized reading' (duration: 243.011185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:59.961765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.450295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.146\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-16T16:42:59.961842Z","caller":"traceutil/trace.go:171","msg":"trace[1275136513] range","detail":"{range_begin:/registry/masterleases/199.19.213.146; range_end:; response_count:1; response_revision:5670; }","duration":"243.571978ms","start":"2026-04-16T16:42:59.718249Z","end":"2026-04-16T16:42:59.961821Z","steps":["trace[1275136513] 'agreement among raft nodes before linearized reading' (duration: 243.364233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:59.96188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.176295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-16T16:42:59.962223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.156942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-16T16:42:59.961932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.485248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T16:42:59.962292Z","caller":"traceutil/trace.go:171","msg":"trace[2778167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5670; }","duration":"103.217684ms","start":"2026-04-16T16:42:59.859053Z","end":"2026-04-16T16:42:59.962271Z","steps":["trace[2778167] 'agreement among raft nodes before linearized reading' (duration: 103.095281ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:59.962288Z","caller":"traceutil/trace.go:171","msg":"trace[2046785768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5670; }","duration":"104.561564ms","start":"2026-04-16T16:42:59.857671Z","end":"2026-04-16T16:42:59.962233Z","steps":["trace[2046785768] 'agreement among raft nodes before linearized reading' (duration: 103.998301ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:42:59.962337Z","caller":"traceutil/trace.go:171","msg":"trace[1082650515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5670; }","duration":"110.961889ms","start":"2026-04-16T16:42:59.851362Z","end":"2026-04-16T16:42:59.962324Z","steps":["trace[1082650515] 'agreement among raft nodes before linearized reading' (duration: 110.404816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:59.962052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.924371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T16:42:59.962409Z","caller":"traceutil/trace.go:171","msg":"trace[320816908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5670; }","duration":"170.30489ms","start":"2026-04-16T16:42:59.792073Z","end":"2026-04-16T16:42:59.962378Z","steps":["trace[320816908] 'agreement among raft nodes before linearized reading' (duration: 169.83937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:42:59.962083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.852881ms","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-04-16T16:42:59.962515Z","caller":"traceutil/trace.go:171","msg":"trace[11009105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5670; }","duration":"109.327752ms","start":"2026-04-16T16:42:59.853179Z","end":"2026-04-16T16:42:59.962507Z","steps":["trace[11009105] 'agreement among raft nodes before linearized reading' (duration: 108.81509ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:43:00.611683Z","caller":"traceutil/trace.go:171","msg":"trace[1740888447] transaction","detail":"{read_only:false; response_revision:5677; number_of_response:1; }","duration":"139.73594ms","start":"2026-04-16T16:43:00.471917Z","end":"2026-04-16T16:43:00.611653Z","steps":["trace[1740888447] 'process raft request' (duration: 139.511205ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:43:00.72182Z","caller":"traceutil/trace.go:171","msg":"trace[271508142] transaction","detail":"{read_only:false; response_revision:5680; number_of_response:1; }","duration":"102.291203ms","start":"2026-04-16T16:43:00.619506Z","end":"2026-04-16T16:43:00.721798Z","steps":["trace[271508142] 'process raft request' (duration: 102.231391ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:43:00.721873Z","caller":"traceutil/trace.go:171","msg":"trace[1301728900] transaction","detail":"{read_only:false; response_revision:5679; number_of_response:1; }","duration":"103.498941ms","start":"2026-04-16T16:43:00.618349Z","end":"2026-04-16T16:43:00.721848Z","steps":["trace[1301728900] 'process raft request' (duration: 74.422984ms)","trace[1301728900] 'compare' (duration: 28.867712ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:43:00.891345Z","caller":"traceutil/trace.go:171","msg":"trace[343362008] transaction","detail":"{read_only:false; response_revision:5682; number_of_response:1; }","duration":"125.036258ms","start":"2026-04-16T16:43:00.766276Z","end":"2026-04-16T16:43:00.891312Z","steps":["trace[343362008] 'process raft request' (duration: 81.396671ms)","trace[343362008] 'compare' (duration: 43.491964ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:43:36.606607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2957} {"level":"info","ts":"2026-04-16T16:43:36.679206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2957,"took":"71.012256ms","hash":1329179427,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":33284096,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-16T16:43:36.67926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1329179427,"revision":2957,"compact-revision":-1} {"level":"info","ts":"2026-04-16T16:48:36.612624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5993} {"level":"info","ts":"2026-04-16T16:48:36.714048Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5993,"took":"99.486987ms","hash":2290376078,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":21553152,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-16T16:48:36.714126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2290376078,"revision":5993,"compact-revision":2957} {"level":"info","ts":"2026-04-16T16:49:00.555268Z","caller":"traceutil/trace.go:171","msg":"trace[1655350342] linearizableReadLoop","detail":"{readStateIndex:8673; appliedIndex:8672; }","duration":"255.54784ms","start":"2026-04-16T16:49:00.299677Z","end":"2026-04-16T16:49:00.555224Z","steps":["trace[1655350342] 'read index received' (duration: 255.393316ms)","trace[1655350342] 'applied index is now lower than readState.Index' (duration: 153.564µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:49:00.555526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.794615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6vcvw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-16T16:49:00.555568Z","caller":"traceutil/trace.go:171","msg":"trace[493820163] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6vcvw; range_end:; response_count:1; response_revision:8378; }","duration":"255.930459ms","start":"2026-04-16T16:49:00.299626Z","end":"2026-04-16T16:49:00.555556Z","steps":["trace[493820163] 'agreement among raft nodes before linearized reading' (duration: 255.730534ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:00.555741Z","caller":"traceutil/trace.go:171","msg":"trace[1188071171] transaction","detail":"{read_only:false; response_revision:8378; number_of_response:1; }","duration":"341.828248ms","start":"2026-04-16T16:49:00.213883Z","end":"2026-04-16T16:49:00.555711Z","steps":["trace[1188071171] 'process raft request' (duration: 341.168022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:00.55587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:00.213863Z","time spent":"341.93898ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:49:00.796559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.236889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:49:00.796648Z","caller":"traceutil/trace.go:171","msg":"trace[2048353634] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8378; }","duration":"106.388532ms","start":"2026-04-16T16:49:00.69024Z","end":"2026-04-16T16:49:00.796629Z","steps":["trace[2048353634] 'count revisions from in-memory index tree' (duration: 106.104786ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:12.221193Z","caller":"traceutil/trace.go:171","msg":"trace[609084934] linearizableReadLoop","detail":"{readStateIndex:8732; appliedIndex:8731; }","duration":"355.760082ms","start":"2026-04-16T16:49:11.865409Z","end":"2026-04-16T16:49:12.221169Z","steps":["trace[609084934] 'read index received' (duration: 355.531446ms)","trace[609084934] 'applied index is now lower than readState.Index' (duration: 227.816µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:49:12.22143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.007548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T16:49:12.221411Z","caller":"traceutil/trace.go:171","msg":"trace[990983161] transaction","detail":"{read_only:false; response_revision:8434; number_of_response:1; }","duration":"383.959378ms","start":"2026-04-16T16:49:11.837413Z","end":"2026-04-16T16:49:12.221373Z","steps":["trace[990983161] 'process raft request' (duration: 383.60692ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:12.221473Z","caller":"traceutil/trace.go:171","msg":"trace[769664311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8434; }","duration":"356.068629ms","start":"2026-04-16T16:49:11.865394Z","end":"2026-04-16T16:49:12.221462Z","steps":["trace[769664311] 'agreement among raft nodes before linearized reading' (duration: 355.884725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:12.22151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:11.865127Z","time spent":"356.374776ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:49:12.221596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:11.837378Z","time spent":"384.121872ms","remote":"127.0.0.1:51026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19394,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:49:12.282229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.464678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:49:12.28232Z","caller":"traceutil/trace.go:171","msg":"trace[1888500469] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:8435; }","duration":"391.603702ms","start":"2026-04-16T16:49:11.890697Z","end":"2026-04-16T16:49:12.2823Z","steps":["trace[1888500469] 'agreement among raft nodes before linearized reading' (duration: 391.433998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:12.282365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:11.890685Z","time spent":"391.669443ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:49:12.282353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.026767ms","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:5"} {"level":"warn","ts":"2026-04-16T16:49:12.282369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.878013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-16T16:49:12.282436Z","caller":"traceutil/trace.go:171","msg":"trace[1308856746] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8435; }","duration":"255.16442ms","start":"2026-04-16T16:49:12.027253Z","end":"2026-04-16T16:49:12.282418Z","steps":["trace[1308856746] 'agreement among raft nodes before linearized reading' (duration: 255.000377ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:12.282449Z","caller":"traceutil/trace.go:171","msg":"trace[1409621305] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8435; }","duration":"165.004855ms","start":"2026-04-16T16:49:12.117427Z","end":"2026-04-16T16:49:12.282432Z","steps":["trace[1409621305] 'agreement among raft nodes before linearized reading' (duration: 164.736859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:12.282497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.25966ms","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-04-16T16:49:12.282572Z","caller":"traceutil/trace.go:171","msg":"trace[315631650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8435; }","duration":"409.415803ms","start":"2026-04-16T16:49:11.87314Z","end":"2026-04-16T16:49:12.282556Z","steps":["trace[315631650] 'agreement among raft nodes before linearized reading' (duration: 409.233499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:12.282625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:11.873118Z","time spent":"409.488305ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:49:12.282538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.243796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-16T16:49:12.282721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.58572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T16:49:12.2828Z","caller":"traceutil/trace.go:171","msg":"trace[1344600930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8435; }","duration":"402.687711ms","start":"2026-04-16T16:49:11.880093Z","end":"2026-04-16T16:49:12.282781Z","steps":["trace[1344600930] 'agreement among raft nodes before linearized reading' (duration: 402.418485ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:12.28276Z","caller":"traceutil/trace.go:171","msg":"trace[155937430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8435; }","duration":"415.496101ms","start":"2026-04-16T16:49:11.867243Z","end":"2026-04-16T16:49:12.282739Z","steps":["trace[155937430] 'agreement among raft nodes before linearized reading' (duration: 415.158714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:12.282844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:11.880082Z","time spent":"402.748973ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:49:12.282835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.684339ms","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-04-16T16:49:12.28299Z","caller":"traceutil/trace.go:171","msg":"trace[1583912444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8435; }","duration":"401.886883ms","start":"2026-04-16T16:49:11.881073Z","end":"2026-04-16T16:49:12.28296Z","steps":["trace[1583912444] 'agreement among raft nodes before linearized reading' (duration: 401.395782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:12.282878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:11.867229Z","time spent":"415.632924ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:49:12.283058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:11.881066Z","time spent":"401.981186ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T16:49:14.580569Z","caller":"traceutil/trace.go:171","msg":"trace[1889850156] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"198.797007ms","start":"2026-04-16T16:49:14.381744Z","end":"2026-04-16T16:49:14.580541Z","steps":["trace[1889850156] 'process raft request' (duration: 138.597838ms)","trace[1889850156] 'compare' (duration: 60.023815ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:49:14.580951Z","caller":"traceutil/trace.go:171","msg":"trace[1780123975] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"199.150586ms","start":"2026-04-16T16:49:14.381777Z","end":"2026-04-16T16:49:14.580927Z","steps":["trace[1780123975] 'process raft request' (duration: 198.707236ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:14.581248Z","caller":"traceutil/trace.go:171","msg":"trace[1524307309] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"199.295298ms","start":"2026-04-16T16:49:14.381923Z","end":"2026-04-16T16:49:14.581218Z","steps":["trace[1524307309] 'process raft request' (duration: 198.886219ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:14.58125Z","caller":"traceutil/trace.go:171","msg":"trace[1787597432] linearizableReadLoop","detail":"{readStateIndex:8748; appliedIndex:8744; }","duration":"181.546318ms","start":"2026-04-16T16:49:14.399673Z","end":"2026-04-16T16:49:14.58122Z","steps":["trace[1787597432] 'read index received' (duration: 22.395036ms)","trace[1787597432] 'applied index is now lower than readState.Index' (duration: 159.144002ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:49:14.581336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:49:14.581376Z","caller":"traceutil/trace.go:171","msg":"trace[191552977] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8450; }","duration":"181.720233ms","start":"2026-04-16T16:49:14.399645Z","end":"2026-04-16T16:49:14.581365Z","steps":["trace[191552977] 'agreement among raft nodes before linearized reading' (duration: 181.60096ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:14.730994Z","caller":"traceutil/trace.go:171","msg":"trace[765860787] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"129.858152ms","start":"2026-04-16T16:49:14.6011Z","end":"2026-04-16T16:49:14.730958Z","steps":["trace[765860787] 'process raft request' (duration: 93.086403ms)","trace[765860787] 'compare' (duration: 36.528474ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:49:45.576586Z","caller":"traceutil/trace.go:171","msg":"trace[1022169776] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"103.074577ms","start":"2026-04-16T16:49:45.473477Z","end":"2026-04-16T16:49:45.576551Z","steps":["trace[1022169776] 'process raft request' (duration: 102.884582ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:54.723816Z","caller":"traceutil/trace.go:171","msg":"trace[262992477] linearizableReadLoop","detail":"{readStateIndex:9065; appliedIndex:9064; }","duration":"112.353457ms","start":"2026-04-16T16:49:54.61144Z","end":"2026-04-16T16:49:54.723793Z","steps":["trace[262992477] 'read index received' (duration: 112.07916ms)","trace[262992477] 'applied index is now lower than readState.Index' (duration: 273.337µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:49:54.723936Z","caller":"traceutil/trace.go:171","msg":"trace[1169044102] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"139.964649ms","start":"2026-04-16T16:49:54.583946Z","end":"2026-04-16T16:49:54.72391Z","steps":["trace[1169044102] 'process raft request' (duration: 139.712224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:54.724026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.572411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T16:49:54.724109Z","caller":"traceutil/trace.go:171","msg":"trace[840499975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8755; }","duration":"112.665183ms","start":"2026-04-16T16:49:54.611394Z","end":"2026-04-16T16:49:54.724059Z","steps":["trace[840499975] 'agreement among raft nodes before linearized reading' (duration: 112.51737ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:49:55.096642Z","caller":"traceutil/trace.go:171","msg":"trace[856272523] linearizableReadLoop","detail":"{readStateIndex:9068; appliedIndex:9067; }","duration":"319.712537ms","start":"2026-04-16T16:49:54.7769Z","end":"2026-04-16T16:49:55.096613Z","steps":["trace[856272523] 'read index received' (duration: 236.748194ms)","trace[856272523] 'applied index is now lower than readState.Index' (duration: 82.963103ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:49:55.096748Z","caller":"traceutil/trace.go:171","msg":"trace[2030196157] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"356.64063ms","start":"2026-04-16T16:49:54.740083Z","end":"2026-04-16T16:49:55.096723Z","steps":["trace[2030196157] 'process raft request' (duration: 273.624336ms)","trace[2030196157] 'compare' (duration: 82.625866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:49:55.096862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:54.740069Z","time spent":"356.728823ms","remote":"127.0.0.1:51030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4635,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:49:55.097035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.969833ms","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-04-16T16:49:55.097086Z","caller":"traceutil/trace.go:171","msg":"trace[288495861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8758; }","duration":"320.216598ms","start":"2026-04-16T16:49:54.776856Z","end":"2026-04-16T16:49:55.097072Z","steps":["trace[288495861] 'agreement among raft nodes before linearized reading' (duration: 319.880581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:55.097122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:54.776839Z","time spent":"320.27432ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T16:49:55.125119Z","caller":"traceutil/trace.go:171","msg":"trace[1610161719] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"260.806478ms","start":"2026-04-16T16:49:54.864289Z","end":"2026-04-16T16:49:55.125096Z","steps":["trace[1610161719] 'process raft request' (duration: 260.687725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:55.19115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.909598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6vcvw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-16T16:49:55.191289Z","caller":"traceutil/trace.go:171","msg":"trace[1905594708] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6vcvw; range_end:; response_count:1; response_revision:8760; }","duration":"217.061391ms","start":"2026-04-16T16:49:54.974206Z","end":"2026-04-16T16:49:55.191268Z","steps":["trace[1905594708] 'agreement among raft nodes before linearized reading' (duration: 216.738604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:55.191914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.079325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:49:55.19198Z","caller":"traceutil/trace.go:171","msg":"trace[2009414798] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:8760; }","duration":"180.188808ms","start":"2026-04-16T16:49:55.011777Z","end":"2026-04-16T16:49:55.191966Z","steps":["trace[2009414798] 'agreement among raft nodes before linearized reading' (duration: 180.043934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:55.192976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.250578ms","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-04-16T16:49:55.193024Z","caller":"traceutil/trace.go:171","msg":"trace[1745712612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8760; }","duration":"409.350791ms","start":"2026-04-16T16:49:54.783661Z","end":"2026-04-16T16:49:55.193011Z","steps":["trace[1745712612] 'agreement among raft nodes before linearized reading' (duration: 409.206248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:55.193072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:54.783646Z","time spent":"409.407713ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:49:55.193072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.529918ms","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-04-16T16:49:55.193174Z","caller":"traceutil/trace.go:171","msg":"trace[210382348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8760; }","duration":"406.6459ms","start":"2026-04-16T16:49:54.786492Z","end":"2026-04-16T16:49:55.193137Z","steps":["trace[210382348] 'agreement among raft nodes before linearized reading' (duration: 406.388864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:55.19323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:49:54.786464Z","time spent":"406.755693ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T16:49:55.811738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.267369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-16T16:49:55.811894Z","caller":"traceutil/trace.go:171","msg":"trace[1380345477] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:8769; }","duration":"114.437654ms","start":"2026-04-16T16:49:55.697434Z","end":"2026-04-16T16:49:55.811872Z","steps":["trace[1380345477] 'range keys from in-memory index tree' (duration: 114.146018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:49:55.81219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.123714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-16T16:49:55.812275Z","caller":"traceutil/trace.go:171","msg":"trace[1252102043] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8769; }","duration":"113.220906ms","start":"2026-04-16T16:49:55.699041Z","end":"2026-04-16T16:49:55.812262Z","steps":["trace[1252102043] 'range keys from in-memory index tree' (duration: 113.035752ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:50:19.760531Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000248f.wal"} {"level":"info","ts":"2026-04-16T16:51:13.605874Z","caller":"traceutil/trace.go:171","msg":"trace[450035502] transaction","detail":"{read_only:false; response_revision:9520; number_of_response:1; }","duration":"338.640805ms","start":"2026-04-16T16:51:13.267208Z","end":"2026-04-16T16:51:13.605848Z","steps":["trace[450035502] 'process raft request' (duration: 338.482211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:51:13.606036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:51:13.267183Z","time spent":"338.779089ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:51:13.621029Z","caller":"traceutil/trace.go:171","msg":"trace[1557133241] transaction","detail":"{read_only:false; response_revision:9521; number_of_response:1; }","duration":"219.473706ms","start":"2026-04-16T16:51:13.401527Z","end":"2026-04-16T16:51:13.621Z","steps":["trace[1557133241] 'process raft request' (duration: 219.2246ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:51:13.621352Z","caller":"traceutil/trace.go:171","msg":"trace[2113980654] transaction","detail":"{read_only:false; response_revision:9522; number_of_response:1; }","duration":"100.479859ms","start":"2026-04-16T16:51:13.520844Z","end":"2026-04-16T16:51:13.621323Z","steps":["trace[2113980654] 'process raft request' (duration: 100.116081ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:51:13.77637Z","caller":"traceutil/trace.go:171","msg":"trace[1496589577] transaction","detail":"{read_only:false; response_revision:9525; number_of_response:1; }","duration":"150.046098ms","start":"2026-04-16T16:51:13.626293Z","end":"2026-04-16T16:51:13.776339Z","steps":["trace[1496589577] 'process raft request' (duration: 149.828393ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:51:13.776655Z","caller":"traceutil/trace.go:171","msg":"trace[1839630312] transaction","detail":"{read_only:false; response_revision:9526; number_of_response:1; }","duration":"150.086127ms","start":"2026-04-16T16:51:13.626534Z","end":"2026-04-16T16:51:13.77662Z","steps":["trace[1839630312] 'process raft request' (duration: 149.75893ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:51:29.858207Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T16:51:29.864152Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-16T16:51:29.864307Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-16T16:53:36.619393Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8071} {"level":"info","ts":"2026-04-16T16:53:36.685939Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8071,"took":"63.911417ms","hash":401863828,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":29147136,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-16T16:53:36.685997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":401863828,"revision":8071,"compact-revision":5993} {"level":"info","ts":"2026-04-16T16:58:36.627881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10633} {"level":"info","ts":"2026-04-16T16:58:36.710087Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10633,"took":"79.981448ms","hash":235149318,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":21454848,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-16T16:58:36.710168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":235149318,"revision":10633,"compact-revision":8071}