{"level":"warn","ts":"2026-03-31T05:28:09.485796Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T05:28:09.485895Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.68: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.68:2380","--initial-cluster=instance=https://199.19.213.68:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.68:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.68: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-03-31T05:28:09.485973Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T05:28:09.485983Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.68:2380"]} {"level":"info","ts":"2026-03-31T05:28:09.486002Z","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-03-31T05:28:09.486678Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.68:2379"]} {"level":"info","ts":"2026-03-31T05:28:09.486762Z","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.68:2380"],"listen-peer-urls":["https://199.19.213.68:2380"],"advertise-client-urls":["https://199.19.213.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.68: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-03-31T05:28:09.490151Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.168075ms"} {"level":"info","ts":"2026-03-31T05:28:09.498039Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"78a0acde880f698","cluster-id":"45d27515d88ba308"} {"level":"info","ts":"2026-03-31T05:28:09.498214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T05:28:09.498258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became follower at term 0"} {"level":"info","ts":"2026-03-31T05:28:09.498281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 78a0acde880f698 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T05:28:09.498296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became follower at term 1"} {"level":"info","ts":"2026-03-31T05:28:09.498363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 switched to configuration voters=(543258584536905368)"} {"level":"warn","ts":"2026-03-31T05:28:09.509852Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T05:28:09.514085Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T05:28:09.515696Z","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-03-31T05:28:09.518676Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"78a0acde880f698","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T05:28:09.518896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"78a0acde880f698","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T05:28:09.518958Z","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-03-31T05:28:09.51907Z","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-03-31T05:28:09.519085Z","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-03-31T05:28:09.521879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 switched to configuration voters=(543258584536905368)"} {"level":"info","ts":"2026-03-31T05:28:09.523049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45d27515d88ba308","local-member-id":"78a0acde880f698","added-peer-id":"78a0acde880f698","added-peer-peer-urls":["https://199.19.213.68:2380"]} {"level":"info","ts":"2026-03-31T05:28:09.524449Z","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-03-31T05:28:09.524609Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.68:2380"} {"level":"info","ts":"2026-03-31T05:28:09.524747Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.68:2380"} {"level":"info","ts":"2026-03-31T05:28:09.5248Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"78a0acde880f698","initial-advertise-peer-urls":["https://199.19.213.68:2380"],"listen-peer-urls":["https://199.19.213.68:2380"],"advertise-client-urls":["https://199.19.213.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T05:28:09.524869Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T05:28:10.299572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T05:28:10.299667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T05:28:10.299714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 received MsgPreVoteResp from 78a0acde880f698 at term 1"} {"level":"info","ts":"2026-03-31T05:28:10.29974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became candidate at term 2"} {"level":"info","ts":"2026-03-31T05:28:10.299755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 received MsgVoteResp from 78a0acde880f698 at term 2"} {"level":"info","ts":"2026-03-31T05:28:10.299778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became leader at term 2"} {"level":"info","ts":"2026-03-31T05:28:10.299797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 78a0acde880f698 elected leader 78a0acde880f698 at term 2"} {"level":"info","ts":"2026-03-31T05:28:10.3009Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"78a0acde880f698","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.68:2379]}","request-path":"/0/members/78a0acde880f698/attributes","cluster-id":"45d27515d88ba308","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T05:28:10.300922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T05:28:10.301033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T05:28:10.300992Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:28:10.301245Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T05:28:10.301327Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T05:28:10.302082Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45d27515d88ba308","local-member-id":"78a0acde880f698","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:28:10.302317Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:28:10.302374Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:28:10.304672Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T05:28:10.30539Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.68:2379"} {"level":"info","ts":"2026-03-31T05:28:29.953773Z","caller":"traceutil/trace.go:171","msg":"trace[1832216116] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"123.428165ms","start":"2026-03-31T05:28:29.83032Z","end":"2026-03-31T05:28:29.953748Z","steps":["trace[1832216116] 'read index received' (duration: 123.306313ms)","trace[1832216116] 'applied index is now lower than readState.Index' (duration: 120.502µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:28:29.953827Z","caller":"traceutil/trace.go:171","msg":"trace[1306031955] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"140.852108ms","start":"2026-03-31T05:28:29.812953Z","end":"2026-03-31T05:28:29.953805Z","steps":["trace[1306031955] 'process raft request' (duration: 140.621072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:29.953955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.58268ms","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-03-31T05:28:29.954022Z","caller":"traceutil/trace.go:171","msg":"trace[1486654201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"123.741293ms","start":"2026-03-31T05:28:29.830271Z","end":"2026-03-31T05:28:29.954012Z","steps":["trace[1486654201] 'agreement among raft nodes before linearized reading' (duration: 123.59188ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:28:30.183049Z","caller":"traceutil/trace.go:171","msg":"trace[1689144279] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"223.984605ms","start":"2026-03-31T05:28:29.959027Z","end":"2026-03-31T05:28:30.183011Z","steps":["trace[1689144279] 'process raft request' (duration: 125.674208ms)","trace[1689144279] 'compare' (duration: 98.129673ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:28:49.138183Z","caller":"traceutil/trace.go:171","msg":"trace[936546757] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"139.897566ms","start":"2026-03-31T05:28:48.998252Z","end":"2026-03-31T05:28:49.13815Z","steps":["trace[936546757] 'read index received' (duration: 139.084458ms)","trace[936546757] 'applied index is now lower than readState.Index' (duration: 812.138µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:28:49.138215Z","caller":"traceutil/trace.go:171","msg":"trace[2113569667] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"417.20569ms","start":"2026-03-31T05:28:48.720978Z","end":"2026-03-31T05:28:49.138184Z","steps":["trace[2113569667] 'process raft request' (duration: 416.580736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.138433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.128001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.138539Z","caller":"traceutil/trace.go:171","msg":"trace[1936413652] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:581; }","duration":"140.305505ms","start":"2026-03-31T05:28:48.998211Z","end":"2026-03-31T05:28:49.138516Z","steps":["trace[1936413652] 'agreement among raft nodes before linearized reading' (duration: 140.07448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.138878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:28:48.720939Z","time spent":"417.400504ms","remote":"127.0.0.1:49530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:28:49.174813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.486169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.174923Z","caller":"traceutil/trace.go:171","msg":"trace[113813258] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:581; }","duration":"176.653713ms","start":"2026-03-31T05:28:48.998246Z","end":"2026-03-31T05:28:49.1749Z","steps":["trace[113813258] 'agreement among raft nodes before linearized reading' (duration: 176.466559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.175774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.179625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.175816Z","caller":"traceutil/trace.go:171","msg":"trace[1108658520] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:581; }","duration":"121.247337ms","start":"2026-03-31T05:28:49.054555Z","end":"2026-03-31T05:28:49.175803Z","steps":["trace[1108658520] 'agreement among raft nodes before linearized reading' (duration: 121.193526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.175952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.727987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.175984Z","caller":"traceutil/trace.go:171","msg":"trace[864724404] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:581; }","duration":"121.81562ms","start":"2026-03-31T05:28:49.054159Z","end":"2026-03-31T05:28:49.175975Z","steps":["trace[864724404] 'agreement among raft nodes before linearized reading' (duration: 121.770349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.176103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.993914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.176135Z","caller":"traceutil/trace.go:171","msg":"trace[1325164164] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:581; }","duration":"122.076375ms","start":"2026-03-31T05:28:49.05405Z","end":"2026-03-31T05:28:49.176126Z","steps":["trace[1325164164] 'agreement among raft nodes before linearized reading' (duration: 122.029544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.176264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.863442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.176296Z","caller":"traceutil/trace.go:171","msg":"trace[1986116202] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:581; }","duration":"148.954245ms","start":"2026-03-31T05:28:49.027333Z","end":"2026-03-31T05:28:49.176287Z","steps":["trace[1986116202] 'agreement among raft nodes before linearized reading' (duration: 148.907374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.176462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.350768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.176494Z","caller":"traceutil/trace.go:171","msg":"trace[1067395924] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:581; }","duration":"150.386438ms","start":"2026-03-31T05:28:49.0261Z","end":"2026-03-31T05:28:49.176487Z","steps":["trace[1067395924] 'agreement among raft nodes before linearized reading' (duration: 150.336517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.176623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.579662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.176657Z","caller":"traceutil/trace.go:171","msg":"trace[1580486113] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:581; }","duration":"150.638804ms","start":"2026-03-31T05:28:49.026008Z","end":"2026-03-31T05:28:49.176646Z","steps":["trace[1580486113] 'agreement among raft nodes before linearized reading' (duration: 150.589663ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:28:49.638878Z","caller":"traceutil/trace.go:171","msg":"trace[805697779] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"258.026019ms","start":"2026-03-31T05:28:49.380826Z","end":"2026-03-31T05:28:49.638852Z","steps":["trace[805697779] 'read index received' (duration: 235.916639ms)","trace[805697779] 'applied index is now lower than readState.Index' (duration: 22.10838ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:28:49.639068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.198563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.639168Z","caller":"traceutil/trace.go:171","msg":"trace[356181770] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:583; }","duration":"258.334166ms","start":"2026-03-31T05:28:49.380811Z","end":"2026-03-31T05:28:49.639145Z","steps":["trace[356181770] 'agreement among raft nodes before linearized reading' (duration: 258.160952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.639215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.338201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.63928Z","caller":"traceutil/trace.go:171","msg":"trace[43639592] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:583; }","duration":"220.444584ms","start":"2026-03-31T05:28:49.418822Z","end":"2026-03-31T05:28:49.639266Z","steps":["trace[43639592] 'agreement among raft nodes before linearized reading' (duration: 220.345221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.63938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.553106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:28:49.639398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.105321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.639458Z","caller":"traceutil/trace.go:171","msg":"trace[348775707] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:583; }","duration":"220.675239ms","start":"2026-03-31T05:28:49.418766Z","end":"2026-03-31T05:28:49.639442Z","steps":["trace[348775707] 'agreement among raft nodes before linearized reading' (duration: 220.564166ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:28:49.639471Z","caller":"traceutil/trace.go:171","msg":"trace[2014062087] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:583; }","duration":"258.392608ms","start":"2026-03-31T05:28:49.381062Z","end":"2026-03-31T05:28:49.639454Z","steps":["trace[2014062087] 'agreement among raft nodes before linearized reading' (duration: 257.943568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:28:49.639525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.115335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:28:49.639632Z","caller":"traceutil/trace.go:171","msg":"trace[223179684] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:583; }","duration":"220.263528ms","start":"2026-03-31T05:28:49.419338Z","end":"2026-03-31T05:28:49.639601Z","steps":["trace[223179684] 'agreement among raft nodes before linearized reading' (duration: 220.047623ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:28:49.641198Z","caller":"traceutil/trace.go:171","msg":"trace[945075471] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"262.695607ms","start":"2026-03-31T05:28:49.37624Z","end":"2026-03-31T05:28:49.638935Z","steps":["trace[945075471] 'process raft request' (duration: 240.565287ms)","trace[945075471] 'compare' (duration: 21.898605ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:29:05.367074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.927512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:29:05.367192Z","caller":"traceutil/trace.go:171","msg":"trace[1740478602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"191.108206ms","start":"2026-03-31T05:29:05.176061Z","end":"2026-03-31T05:29:05.367169Z","steps":["trace[1740478602] 'range keys from in-memory index tree' (duration: 190.792669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:30:21.283426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.502776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:30:21.283426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.140828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d3495778-f843-4855-be16-172f0e64a6b5\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-03-31T05:30:21.283582Z","caller":"traceutil/trace.go:171","msg":"trace[1660687584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1822; }","duration":"109.735791ms","start":"2026-03-31T05:30:21.17382Z","end":"2026-03-31T05:30:21.283556Z","steps":["trace[1660687584] 'range keys from in-memory index tree' (duration: 109.309102ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:30:21.283597Z","caller":"traceutil/trace.go:171","msg":"trace[1235729035] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d3495778-f843-4855-be16-172f0e64a6b5; range_end:; response_count:1; response_revision:1822; }","duration":"169.385584ms","start":"2026-03-31T05:30:21.114194Z","end":"2026-03-31T05:30:21.28358Z","steps":["trace[1235729035] 'range keys from in-memory index tree' (duration: 168.901833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:30:35.076895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.402509ms","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-03-31T05:30:35.077014Z","caller":"traceutil/trace.go:171","msg":"trace[848711826] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1941; }","duration":"133.553523ms","start":"2026-03-31T05:30:34.943436Z","end":"2026-03-31T05:30:35.07699Z","steps":["trace[848711826] 'range keys from in-memory index tree' (duration: 133.343528ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:31:06.18785Z","caller":"traceutil/trace.go:171","msg":"trace[223348031] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"114.115433ms","start":"2026-03-31T05:31:06.073682Z","end":"2026-03-31T05:31:06.187797Z","steps":["trace[223348031] 'process raft request' (duration: 113.818296ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:31:12.478804Z","caller":"traceutil/trace.go:171","msg":"trace[1875364131] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2230; }","duration":"254.070196ms","start":"2026-03-31T05:31:12.224707Z","end":"2026-03-31T05:31:12.478777Z","steps":["trace[1875364131] 'read index received' (duration: 253.876231ms)","trace[1875364131] 'applied index is now lower than readState.Index' (duration: 193.005µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:31:12.478904Z","caller":"traceutil/trace.go:171","msg":"trace[1147022852] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"269.498609ms","start":"2026-03-31T05:31:12.20938Z","end":"2026-03-31T05:31:12.478879Z","steps":["trace[1147022852] 'process raft request' (duration: 269.253604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:31:12.478997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.384993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:31:12.479029Z","caller":"traceutil/trace.go:171","msg":"trace[1506432538] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2149; }","duration":"104.502046ms","start":"2026-03-31T05:31:12.37452Z","end":"2026-03-31T05:31:12.479022Z","steps":["trace[1506432538] 'agreement among raft nodes before linearized reading' (duration: 104.400933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:31:12.479146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.397093ms","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-03-31T05:31:12.479269Z","caller":"traceutil/trace.go:171","msg":"trace[1849627871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2149; }","duration":"254.591738ms","start":"2026-03-31T05:31:12.224651Z","end":"2026-03-31T05:31:12.479243Z","steps":["trace[1849627871] 'agreement among raft nodes before linearized reading' (duration: 254.25782ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:35:04.789574Z","caller":"traceutil/trace.go:171","msg":"trace[1062349119] transaction","detail":"{read_only:false; response_revision:3756; number_of_response:1; }","duration":"105.941948ms","start":"2026-03-31T05:35:04.683567Z","end":"2026-03-31T05:35:04.789509Z","steps":["trace[1062349119] 'process raft request' (duration: 72.60542ms)","trace[1062349119] 'compare' (duration: 32.898758ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:35:05.19889Z","caller":"traceutil/trace.go:171","msg":"trace[147675386] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"114.993643ms","start":"2026-03-31T05:35:05.083867Z","end":"2026-03-31T05:35:05.198861Z","steps":["trace[147675386] 'process raft request' (duration: 114.806789ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:35:05.201557Z","caller":"traceutil/trace.go:171","msg":"trace[919777321] transaction","detail":"{read_only:false; response_revision:3760; number_of_response:1; }","duration":"116.54852ms","start":"2026-03-31T05:35:05.084988Z","end":"2026-03-31T05:35:05.201536Z","steps":["trace[919777321] 'process raft request' (duration: 116.445208ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:35:35.461418Z","caller":"traceutil/trace.go:171","msg":"trace[1337262950] transaction","detail":"{read_only:false; response_revision:3945; number_of_response:1; }","duration":"150.524314ms","start":"2026-03-31T05:35:35.310854Z","end":"2026-03-31T05:35:35.461379Z","steps":["trace[1337262950] 'process raft request' (duration: 150.081803ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:35:35.542012Z","caller":"traceutil/trace.go:171","msg":"trace[1197503041] transaction","detail":"{read_only:false; response_revision:3946; number_of_response:1; }","duration":"228.496279ms","start":"2026-03-31T05:35:35.313485Z","end":"2026-03-31T05:35:35.541981Z","steps":["trace[1197503041] 'process raft request' (duration: 228.296485ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:37:04.052831Z","caller":"traceutil/trace.go:171","msg":"trace[1642345665] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"112.119713ms","start":"2026-03-31T05:37:03.940687Z","end":"2026-03-31T05:37:04.052807Z","steps":["trace[1642345665] 'process raft request' (duration: 111.902138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:37:07.055706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.651587ms","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-03-31T05:37:07.055895Z","caller":"traceutil/trace.go:171","msg":"trace[1599756670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5153; }","duration":"138.942094ms","start":"2026-03-31T05:37:06.916918Z","end":"2026-03-31T05:37:07.05586Z","steps":["trace[1599756670] 'range keys from in-memory index tree' (duration: 138.40305ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:37:07.094154Z","caller":"traceutil/trace.go:171","msg":"trace[1946726569] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"114.006788ms","start":"2026-03-31T05:37:06.980111Z","end":"2026-03-31T05:37:07.094118Z","steps":["trace[1946726569] 'process raft request' (duration: 113.701961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:37:09.221132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.264727ms","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-03-31T05:37:09.221219Z","caller":"traceutil/trace.go:171","msg":"trace[1228877617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5191; }","duration":"104.41853ms","start":"2026-03-31T05:37:09.116781Z","end":"2026-03-31T05:37:09.2212Z","steps":["trace[1228877617] 'range keys from in-memory index tree' (duration: 104.055522ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:37:09.355356Z","caller":"traceutil/trace.go:171","msg":"trace[1228928372] linearizableReadLoop","detail":"{readStateIndex:5390; appliedIndex:5389; }","duration":"133.270957ms","start":"2026-03-31T05:37:09.22205Z","end":"2026-03-31T05:37:09.355321Z","steps":["trace[1228928372] 'read index received' (duration: 109.243035ms)","trace[1228928372] 'applied index is now lower than readState.Index' (duration: 24.024242ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:37:09.355511Z","caller":"traceutil/trace.go:171","msg":"trace[2032013325] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"130.673055ms","start":"2026-03-31T05:37:09.224806Z","end":"2026-03-31T05:37:09.355479Z","steps":["trace[2032013325] 'process raft request' (duration: 130.410369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:37:09.355582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.492033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:37:09.355668Z","caller":"traceutil/trace.go:171","msg":"trace[1464262399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5193; }","duration":"133.621366ms","start":"2026-03-31T05:37:09.222027Z","end":"2026-03-31T05:37:09.355648Z","steps":["trace[1464262399] 'agreement among raft nodes before linearized reading' (duration: 133.376099ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:37:09.355726Z","caller":"traceutil/trace.go:171","msg":"trace[2043332145] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"130.730667ms","start":"2026-03-31T05:37:09.224974Z","end":"2026-03-31T05:37:09.355705Z","steps":["trace[2043332145] 'process raft request' (duration: 130.45472ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:37:09.355512Z","caller":"traceutil/trace.go:171","msg":"trace[1642667215] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"130.579133ms","start":"2026-03-31T05:37:09.224898Z","end":"2026-03-31T05:37:09.355477Z","steps":["trace[1642667215] 'process raft request' (duration: 130.475341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:37:21.647891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.610904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:37:21.647979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.115275ms","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-03-31T05:37:21.648043Z","caller":"traceutil/trace.go:171","msg":"trace[1775780212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5426; }","duration":"294.850921ms","start":"2026-03-31T05:37:21.353169Z","end":"2026-03-31T05:37:21.64802Z","steps":["trace[1775780212] 'range keys from in-memory index tree' (duration: 294.449511ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:37:21.648076Z","caller":"traceutil/trace.go:171","msg":"trace[1949932379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5426; }","duration":"232.278578ms","start":"2026-03-31T05:37:21.415776Z","end":"2026-03-31T05:37:21.648054Z","steps":["trace[1949932379] 'range keys from in-memory index tree' (duration: 231.810947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:37:21.648064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.354497ms","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-03-31T05:37:21.648219Z","caller":"traceutil/trace.go:171","msg":"trace[880739787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5426; }","duration":"226.545192ms","start":"2026-03-31T05:37:21.421638Z","end":"2026-03-31T05:37:21.648183Z","steps":["trace[880739787] 'range keys from in-memory index tree' (duration: 226.131191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:37:21.648282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.33316ms","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-03-31T05:37:21.648381Z","caller":"traceutil/trace.go:171","msg":"trace[46165319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5426; }","duration":"232.510174ms","start":"2026-03-31T05:37:21.415848Z","end":"2026-03-31T05:37:21.648359Z","steps":["trace[46165319] 'range keys from in-memory index tree' (duration: 232.096894ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:37:39.508987Z","caller":"traceutil/trace.go:171","msg":"trace[826731930] transaction","detail":"{read_only:false; response_revision:5722; number_of_response:1; }","duration":"125.701667ms","start":"2026-03-31T05:37:39.383259Z","end":"2026-03-31T05:37:39.50896Z","steps":["trace[826731930] 'process raft request' (duration: 92.889385ms)","trace[826731930] 'compare' (duration: 32.396402ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:37:39.509279Z","caller":"traceutil/trace.go:171","msg":"trace[593854528] linearizableReadLoop","detail":"{readStateIndex:5935; appliedIndex:5931; }","duration":"117.171213ms","start":"2026-03-31T05:37:39.392082Z","end":"2026-03-31T05:37:39.509253Z","steps":["trace[593854528] 'read index received' (duration: 9.68886ms)","trace[593854528] 'applied index is now lower than readState.Index' (duration: 107.481283ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:37:39.509301Z","caller":"traceutil/trace.go:171","msg":"trace[1343459195] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"125.586824ms","start":"2026-03-31T05:37:39.38368Z","end":"2026-03-31T05:37:39.509267Z","steps":["trace[1343459195] 'process raft request' (duration: 125.492112ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:37:39.509293Z","caller":"traceutil/trace.go:171","msg":"trace[600225617] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"125.594164ms","start":"2026-03-31T05:37:39.383646Z","end":"2026-03-31T05:37:39.50924Z","steps":["trace[600225617] 'process raft request' (duration: 125.243525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:37:39.509516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.48158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-03-31T05:37:39.509599Z","caller":"traceutil/trace.go:171","msg":"trace[1260279000] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5724; }","duration":"117.618195ms","start":"2026-03-31T05:37:39.391966Z","end":"2026-03-31T05:37:39.509584Z","steps":["trace[1260279000] 'agreement among raft nodes before linearized reading' (duration: 117.360738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:37:39.509822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.376082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T05:37:39.509866Z","caller":"traceutil/trace.go:171","msg":"trace[1053141250] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5724; }","duration":"110.464005ms","start":"2026-03-31T05:37:39.399389Z","end":"2026-03-31T05:37:39.509853Z","steps":["trace[1053141250] 'agreement among raft nodes before linearized reading' (duration: 110.001273ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:38:10.337777Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2982} {"level":"info","ts":"2026-03-31T05:38:10.416264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2982,"took":"76.842746ms","hash":2966692043,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":33382400,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-03-31T05:38:10.416334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2966692043,"revision":2982,"compact-revision":-1} {"level":"info","ts":"2026-03-31T05:43:10.346209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6012} {"level":"info","ts":"2026-03-31T05:43:10.437433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6012,"took":"89.464608ms","hash":2390361853,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":21626880,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-31T05:43:10.437518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2390361853,"revision":6012,"compact-revision":2982} {"level":"info","ts":"2026-03-31T05:43:47.266494Z","caller":"traceutil/trace.go:171","msg":"trace[1829079496] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"278.728069ms","start":"2026-03-31T05:43:46.987729Z","end":"2026-03-31T05:43:47.266457Z","steps":["trace[1829079496] 'process raft request' (duration: 278.532224ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:43:57.602836Z","caller":"traceutil/trace.go:171","msg":"trace[1317516609] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"272.097312ms","start":"2026-03-31T05:43:57.330712Z","end":"2026-03-31T05:43:57.602809Z","steps":["trace[1317516609] 'process raft request' (duration: 271.935487ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:43:57.715015Z","caller":"traceutil/trace.go:171","msg":"trace[1509598276] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"242.313767ms","start":"2026-03-31T05:43:57.472678Z","end":"2026-03-31T05:43:57.714992Z","steps":["trace[1509598276] 'process raft request' (duration: 242.08076ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:43:57.715221Z","caller":"traceutil/trace.go:171","msg":"trace[984581945] linearizableReadLoop","detail":"{readStateIndex:8822; appliedIndex:8819; }","duration":"187.083949ms","start":"2026-03-31T05:43:57.528089Z","end":"2026-03-31T05:43:57.715173Z","steps":["trace[984581945] 'read index received' (duration: 74.590525ms)","trace[984581945] 'applied index is now lower than readState.Index' (duration: 112.490554ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:43:57.715251Z","caller":"traceutil/trace.go:171","msg":"trace[20421509] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"187.628892ms","start":"2026-03-31T05:43:57.52758Z","end":"2026-03-31T05:43:57.715208Z","steps":["trace[20421509] 'process raft request' (duration: 187.374986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:43:57.71535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.241572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:43:57.715391Z","caller":"traceutil/trace.go:171","msg":"trace[2014069886] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8521; }","duration":"187.326985ms","start":"2026-03-31T05:43:57.528054Z","end":"2026-03-31T05:43:57.715381Z","steps":["trace[2014069886] 'agreement among raft nodes before linearized reading' (duration: 187.255503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:43:57.771929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.092695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-31T05:43:57.772009Z","caller":"traceutil/trace.go:171","msg":"trace[1472111627] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8522; }","duration":"126.221688ms","start":"2026-03-31T05:43:57.645771Z","end":"2026-03-31T05:43:57.771993Z","steps":["trace[1472111627] 'agreement among raft nodes before linearized reading' (duration: 126.003943ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:43:57.898707Z","caller":"traceutil/trace.go:171","msg":"trace[554290002] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"122.602752ms","start":"2026-03-31T05:43:57.776076Z","end":"2026-03-31T05:43:57.898679Z","steps":["trace[554290002] 'process raft request' (duration: 22.38195ms)","trace[554290002] 'compare' (duration: 99.085166ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:44:29.549247Z","caller":"traceutil/trace.go:171","msg":"trace[643508927] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"281.939164ms","start":"2026-03-31T05:44:29.267285Z","end":"2026-03-31T05:44:29.549224Z","steps":["trace[643508927] 'process raft request' (duration: 281.820801ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:44:29.549603Z","caller":"traceutil/trace.go:171","msg":"trace[175851753] linearizableReadLoop","detail":"{readStateIndex:9056; appliedIndex:9056; }","duration":"149.825246ms","start":"2026-03-31T05:44:29.399772Z","end":"2026-03-31T05:44:29.549597Z","steps":["trace[175851753] 'read index received' (duration: 149.821926ms)","trace[175851753] 'applied index is now lower than readState.Index' (duration: 2.96µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:44:29.549661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.876107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:44:29.549682Z","caller":"traceutil/trace.go:171","msg":"trace[82340908] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:8746; }","duration":"149.908807ms","start":"2026-03-31T05:44:29.399766Z","end":"2026-03-31T05:44:29.549674Z","steps":["trace[82340908] 'agreement among raft nodes before linearized reading' (duration: 149.860626ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:44:32.385284Z","caller":"traceutil/trace.go:171","msg":"trace[15603799] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"115.614401ms","start":"2026-03-31T05:44:32.269639Z","end":"2026-03-31T05:44:32.385254Z","steps":["trace[15603799] 'process raft request' (duration: 88.92525ms)","trace[15603799] 'compare' (duration: 26.416894ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:44:44.431183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.844709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:44:44.431481Z","caller":"traceutil/trace.go:171","msg":"trace[1087211145] linearizableReadLoop","detail":"{readStateIndex:9186; appliedIndex:9185; }","duration":"258.837572ms","start":"2026-03-31T05:44:44.172621Z","end":"2026-03-31T05:44:44.431458Z","steps":["trace[1087211145] 'read index received' (duration: 133.248897ms)","trace[1087211145] 'applied index is now lower than readState.Index' (duration: 125.586555ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:44:44.431584Z","caller":"traceutil/trace.go:171","msg":"trace[1570412360] transaction","detail":"{read_only:false; response_revision:8870; number_of_response:1; }","duration":"340.844649ms","start":"2026-03-31T05:44:44.090687Z","end":"2026-03-31T05:44:44.431532Z","steps":["trace[1570412360] 'process raft request' (duration: 215.298094ms)","trace[1570412360] 'compare' (duration: 124.560701ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:44:44.431703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.046597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:44:44.431793Z","caller":"traceutil/trace.go:171","msg":"trace[1511674472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8870; }","duration":"259.201731ms","start":"2026-03-31T05:44:44.172571Z","end":"2026-03-31T05:44:44.431773Z","steps":["trace[1511674472] 'agreement among raft nodes before linearized reading' (duration: 259.028817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:44:44.431836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:44:44.09066Z","time spent":"341.051583ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:44:44.431936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.675241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-31T05:44:44.431968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.687228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-03-31T05:44:44.431993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.593904ms","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-03-31T05:44:44.431954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.228073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-31T05:44:44.432069Z","caller":"traceutil/trace.go:171","msg":"trace[744132999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8870; }","duration":"226.377445ms","start":"2026-03-31T05:44:44.205683Z","end":"2026-03-31T05:44:44.432061Z","steps":["trace[744132999] 'agreement among raft nodes before linearized reading' (duration: 226.110129ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:44:44.432067Z","caller":"traceutil/trace.go:171","msg":"trace[591777229] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8870; }","duration":"103.849462ms","start":"2026-03-31T05:44:44.328197Z","end":"2026-03-31T05:44:44.432046Z","steps":["trace[591777229] 'agreement among raft nodes before linearized reading' (duration: 103.548495ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:44:44.43208Z","caller":"traceutil/trace.go:171","msg":"trace[1138162883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8870; }","duration":"230.677106ms","start":"2026-03-31T05:44:44.201381Z","end":"2026-03-31T05:44:44.432058Z","steps":["trace[1138162883] 'agreement among raft nodes before linearized reading' (duration: 230.446711ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:44:44.432033Z","caller":"traceutil/trace.go:171","msg":"trace[896451538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8870; }","duration":"231.804994ms","start":"2026-03-31T05:44:44.200203Z","end":"2026-03-31T05:44:44.432008Z","steps":["trace[896451538] 'agreement among raft nodes before linearized reading' (duration: 231.533888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:44:44.432521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.82742ms","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-03-31T05:44:44.432577Z","caller":"traceutil/trace.go:171","msg":"trace[778213369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8870; }","duration":"230.925922ms","start":"2026-03-31T05:44:44.201636Z","end":"2026-03-31T05:44:44.432562Z","steps":["trace[778213369] 'agreement among raft nodes before linearized reading' (duration: 230.124403ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:44:56.994121Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245d.wal"} {"level":"info","ts":"2026-03-31T05:46:06.586415Z","caller":"traceutil/trace.go:171","msg":"trace[1482167542] transaction","detail":"{read_only:false; response_revision:9646; number_of_response:1; }","duration":"317.567928ms","start":"2026-03-31T05:46:06.268819Z","end":"2026-03-31T05:46:06.586387Z","steps":["trace[1482167542] 'process raft request' (duration: 283.212237ms)","trace[1482167542] 'compare' (duration: 34.054744ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:46:06.586544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:46:06.268792Z","time spent":"317.684911ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:46:06.590865Z","caller":"traceutil/trace.go:171","msg":"trace[864135396] transaction","detail":"{read_only:false; response_revision:9647; number_of_response:1; }","duration":"258.712329ms","start":"2026-03-31T05:46:06.332082Z","end":"2026-03-31T05:46:06.590794Z","steps":["trace[864135396] 'process raft request' (duration: 258.462283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:06.590982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.804203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-31T05:46:06.591019Z","caller":"traceutil/trace.go:171","msg":"trace[1411306337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9647; }","duration":"192.883535ms","start":"2026-03-31T05:46:06.398125Z","end":"2026-03-31T05:46:06.591009Z","steps":["trace[1411306337] 'agreement among raft nodes before linearized reading' (duration: 192.749961ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:46:06.59101Z","caller":"traceutil/trace.go:171","msg":"trace[590967284] linearizableReadLoop","detail":"{readStateIndex:9983; appliedIndex:9981; }","duration":"192.518186ms","start":"2026-03-31T05:46:06.398178Z","end":"2026-03-31T05:46:06.590697Z","steps":["trace[590967284] 'read index received' (duration: 153.945096ms)","trace[590967284] 'applied index is now lower than readState.Index' (duration: 38.57202ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:46:08.951618Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"78a0acde880f698","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T05:46:08.956054Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-31T05:46:08.956173Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-03-31T05:46:58.714913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.389527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19219"} {"level":"info","ts":"2026-03-31T05:46:58.714982Z","caller":"traceutil/trace.go:171","msg":"trace[1043359407] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10009; }","duration":"103.48107ms","start":"2026-03-31T05:46:58.611485Z","end":"2026-03-31T05:46:58.714966Z","steps":["trace[1043359407] 'range keys from in-memory index tree' (duration: 103.259225ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:46:59.006084Z","caller":"traceutil/trace.go:171","msg":"trace[1040534745] transaction","detail":"{read_only:false; response_revision:10010; number_of_response:1; }","duration":"197.871234ms","start":"2026-03-31T05:46:58.808183Z","end":"2026-03-31T05:46:59.006054Z","steps":["trace[1040534745] 'process raft request' (duration: 197.662028ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:46:59.111179Z","caller":"traceutil/trace.go:171","msg":"trace[1550684409] transaction","detail":"{read_only:false; response_revision:10011; number_of_response:1; }","duration":"288.751715ms","start":"2026-03-31T05:46:58.822385Z","end":"2026-03-31T05:46:59.111137Z","steps":["trace[1550684409] 'process raft request' (duration: 288.447737ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:46:59.193963Z","caller":"traceutil/trace.go:171","msg":"trace[2113898271] linearizableReadLoop","detail":"{readStateIndex:10359; appliedIndex:10356; }","duration":"287.438014ms","start":"2026-03-31T05:46:58.90646Z","end":"2026-03-31T05:46:59.193898Z","steps":["trace[2113898271] 'read index received' (duration: 99.455984ms)","trace[2113898271] 'applied index is now lower than readState.Index' (duration: 187.98038ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:46:59.19429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.808562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-31T05:46:59.194406Z","caller":"traceutil/trace.go:171","msg":"trace[1666561707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10011; }","duration":"287.978206ms","start":"2026-03-31T05:46:58.906401Z","end":"2026-03-31T05:46:59.194379Z","steps":["trace[1666561707] 'agreement among raft nodes before linearized reading' (duration: 287.70243ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:46:59.194474Z","caller":"traceutil/trace.go:171","msg":"trace[511851991] transaction","detail":"{read_only:false; response_revision:10012; number_of_response:1; }","duration":"137.517255ms","start":"2026-03-31T05:46:59.05688Z","end":"2026-03-31T05:46:59.194398Z","steps":["trace[511851991] 'process raft request' (duration: 136.87885ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:48:10.353114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8099} {"level":"info","ts":"2026-03-31T05:48:10.414098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8099,"took":"58.814522ms","hash":3152563838,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":28057600,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-31T05:48:10.414155Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3152563838,"revision":8099,"compact-revision":6012} {"level":"info","ts":"2026-03-31T05:53:10.361268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10505} {"level":"info","ts":"2026-03-31T05:53:10.443257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10505,"took":"79.293526ms","hash":1989839954,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":26075136,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-31T05:53:10.443338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1989839954,"revision":10505,"compact-revision":8099} {"level":"info","ts":"2026-03-31T05:58:10.371294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12630} {"level":"info","ts":"2026-03-31T05:58:10.448478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12630,"took":"74.215386ms","hash":3210148778,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":27447296,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-31T05:58:10.448548Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3210148778,"revision":12630,"compact-revision":10505} {"level":"info","ts":"2026-03-31T05:58:35.299231Z","caller":"traceutil/trace.go:171","msg":"trace[1127127266] transaction","detail":"{read_only:false; response_revision:14800; number_of_response:1; }","duration":"102.033897ms","start":"2026-03-31T05:58:35.197146Z","end":"2026-03-31T05:58:35.29918Z","steps":["trace[1127127266] 'process raft request' (duration: 101.707078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:58:36.159341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.886293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:58:36.159481Z","caller":"traceutil/trace.go:171","msg":"trace[898920414] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:14805; }","duration":"221.058657ms","start":"2026-03-31T05:58:35.938401Z","end":"2026-03-31T05:58:36.15946Z","steps":["trace[898920414] 'count revisions from in-memory index tree' (duration: 220.77248ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:58:36.160732Z","caller":"traceutil/trace.go:171","msg":"trace[402411593] linearizableReadLoop","detail":"{readStateIndex:15321; appliedIndex:15320; }","duration":"191.039113ms","start":"2026-03-31T05:58:35.969661Z","end":"2026-03-31T05:58:36.1607Z","steps":["trace[402411593] 'read index received' (duration: 165.086136ms)","trace[402411593] 'applied index is now lower than readState.Index' (duration: 25.951897ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:58:36.160836Z","caller":"traceutil/trace.go:171","msg":"trace[229219919] transaction","detail":"{read_only:false; response_revision:14806; number_of_response:1; }","duration":"206.745467ms","start":"2026-03-31T05:58:35.954034Z","end":"2026-03-31T05:58:36.160779Z","steps":["trace[229219919] 'process raft request' (duration: 180.684337ms)","trace[229219919] 'compare' (duration: 25.854785ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:58:36.1609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.203736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:58:36.16118Z","caller":"traceutil/trace.go:171","msg":"trace[865269988] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:14806; }","duration":"191.546065ms","start":"2026-03-31T05:58:35.969616Z","end":"2026-03-31T05:58:36.161162Z","steps":["trace[865269988] 'agreement among raft nodes before linearized reading' (duration: 191.209867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:59:00.784885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.410451ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:59:00.785022Z","caller":"traceutil/trace.go:171","msg":"trace[1030552466] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15125; }","duration":"183.582485ms","start":"2026-03-31T05:59:00.601423Z","end":"2026-03-31T05:59:00.785005Z","steps":["trace[1030552466] 'range keys from in-memory index tree' (duration: 183.388251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:59:00.785046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.375337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d6628f1a-c173-41bd-83cf-9a6995802ca3\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-03-31T05:59:00.785172Z","caller":"traceutil/trace.go:171","msg":"trace[187017802] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d6628f1a-c173-41bd-83cf-9a6995802ca3; range_end:; response_count:1; response_revision:15125; }","duration":"174.568631ms","start":"2026-03-31T05:59:00.610576Z","end":"2026-03-31T05:59:00.785144Z","steps":["trace[187017802] 'range keys from in-memory index tree' (duration: 174.169392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:59:00.78519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.641907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:59:00.785312Z","caller":"traceutil/trace.go:171","msg":"trace[1744674339] linearizableReadLoop","detail":"{readStateIndex:15654; appliedIndex:15653; }","duration":"134.865728ms","start":"2026-03-31T05:59:00.650428Z","end":"2026-03-31T05:59:00.785294Z","steps":["trace[1744674339] 'read index received' (duration: 20.129039ms)","trace[1744674339] 'applied index is now lower than readState.Index' (duration: 114.735519ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:59:00.785377Z","caller":"traceutil/trace.go:171","msg":"trace[1078646458] transaction","detail":"{read_only:false; response_revision:15126; number_of_response:1; }","duration":"159.157356ms","start":"2026-03-31T05:59:00.626177Z","end":"2026-03-31T05:59:00.785334Z","steps":["trace[1078646458] 'process raft request' (duration: 44.258193ms)","trace[1078646458] 'compare' (duration: 114.36388ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:59:00.785504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.078713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-03-31T05:59:00.785545Z","caller":"traceutil/trace.go:171","msg":"trace[1818618043] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15126; }","duration":"135.146815ms","start":"2026-03-31T05:59:00.650386Z","end":"2026-03-31T05:59:00.785532Z","steps":["trace[1818618043] 'agreement among raft nodes before linearized reading' (duration: 134.96558ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:59:00.983548Z","caller":"traceutil/trace.go:171","msg":"trace[1741365320] transaction","detail":"{read_only:false; response_revision:15127; number_of_response:1; }","duration":"191.394812ms","start":"2026-03-31T05:59:00.792133Z","end":"2026-03-31T05:59:00.983527Z","steps":["trace[1741365320] 'process raft request' (duration: 191.072424ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:59:53.794264Z","caller":"traceutil/trace.go:171","msg":"trace[237098971] transaction","detail":"{read_only:false; response_revision:15763; number_of_response:1; }","duration":"101.671459ms","start":"2026-03-31T05:59:53.692555Z","end":"2026-03-31T05:59:53.794226Z","steps":["trace[237098971] 'process raft request' (duration: 101.440293ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:59:53.797528Z","caller":"traceutil/trace.go:171","msg":"trace[1900469264] transaction","detail":"{read_only:false; response_revision:15764; number_of_response:1; }","duration":"104.802793ms","start":"2026-03-31T05:59:53.692695Z","end":"2026-03-31T05:59:53.797497Z","steps":["trace[1900469264] 'process raft request' (duration: 104.598508ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:59:53.797788Z","caller":"traceutil/trace.go:171","msg":"trace[799892553] transaction","detail":"{read_only:false; response_revision:15765; number_of_response:1; }","duration":"103.762778ms","start":"2026-03-31T05:59:53.693974Z","end":"2026-03-31T05:59:53.797736Z","steps":["trace[799892553] 'process raft request' (duration: 103.479771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:59:53.798497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.352637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-03-31T05:59:53.79863Z","caller":"traceutil/trace.go:171","msg":"trace[832058046] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15765; }","duration":"100.705055ms","start":"2026-03-31T05:59:53.697895Z","end":"2026-03-31T05:59:53.7986Z","steps":["trace[832058046] 'agreement among raft nodes before linearized reading' (duration: 100.06102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:59:53.79898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.06142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-03-31T05:59:53.79906Z","caller":"traceutil/trace.go:171","msg":"trace[273147350] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15765; }","duration":"101.211007ms","start":"2026-03-31T05:59:53.697826Z","end":"2026-03-31T05:59:53.799037Z","steps":["trace[273147350] 'agreement among raft nodes before linearized reading' (duration: 99.954118ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:00:22.661118Z","caller":"traceutil/trace.go:171","msg":"trace[442748406] transaction","detail":"{read_only:false; response_revision:15946; number_of_response:1; }","duration":"155.770114ms","start":"2026-03-31T06:00:22.505302Z","end":"2026-03-31T06:00:22.661072Z","steps":["trace[442748406] 'process raft request' (duration: 155.485198ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:00:22.704937Z","caller":"traceutil/trace.go:171","msg":"trace[1011040336] transaction","detail":"{read_only:false; response_revision:15947; number_of_response:1; }","duration":"113.262974ms","start":"2026-03-31T06:00:22.591649Z","end":"2026-03-31T06:00:22.704912Z","steps":["trace[1011040336] 'process raft request' (duration: 113.130771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:00:23.020519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.474048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5z5cm\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-03-31T06:00:23.020545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.013993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-03-31T06:00:23.0206Z","caller":"traceutil/trace.go:171","msg":"trace[762256634] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5z5cm; range_end:; response_count:1; response_revision:15947; }","duration":"221.666833ms","start":"2026-03-31T06:00:22.798917Z","end":"2026-03-31T06:00:23.020584Z","steps":["trace[762256634] 'range keys from in-memory index tree' (duration: 221.270903ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:00:23.020624Z","caller":"traceutil/trace.go:171","msg":"trace[1206193987] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15947; }","duration":"236.155087ms","start":"2026-03-31T06:00:22.784452Z","end":"2026-03-31T06:00:23.020607Z","steps":["trace[1206193987] 'range keys from in-memory index tree' (duration: 235.887861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:00:24.461381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.923401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T06:00:24.461526Z","caller":"traceutil/trace.go:171","msg":"trace[1375854623] transaction","detail":"{read_only:false; response_revision:15957; number_of_response:1; }","duration":"203.95609ms","start":"2026-03-31T06:00:24.257546Z","end":"2026-03-31T06:00:24.461502Z","steps":["trace[1375854623] 'process raft request' (duration: 86.807614ms)","trace[1375854623] 'compare' (duration: 116.581143ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:00:24.62965Z","caller":"traceutil/trace.go:171","msg":"trace[730712938] transaction","detail":"{read_only:false; response_revision:15958; number_of_response:1; }","duration":"315.091634ms","start":"2026-03-31T06:00:24.314526Z","end":"2026-03-31T06:00:24.629617Z","steps":["trace[730712938] 'process raft request' (duration: 311.385507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:00:24.630071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:00:24.314501Z","time spent":"315.336189ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:03:10.378284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14657} {"level":"info","ts":"2026-03-31T06:03:10.459748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14657,"took":"79.065935ms","hash":2322362343,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":30908416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-31T06:03:10.459813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2322362343,"revision":14657,"compact-revision":12630} {"level":"info","ts":"2026-03-31T06:07:16.387938Z","caller":"traceutil/trace.go:171","msg":"trace[508712943] linearizableReadLoop","detail":"{readStateIndex:19564; appliedIndex:19563; }","duration":"213.329121ms","start":"2026-03-31T06:07:16.174584Z","end":"2026-03-31T06:07:16.387913Z","steps":["trace[508712943] 'read index received' (duration: 213.221799ms)","trace[508712943] 'applied index is now lower than readState.Index' (duration: 106.382µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:07:16.388168Z","caller":"traceutil/trace.go:171","msg":"trace[1291247342] transaction","detail":"{read_only:false; response_revision:18913; number_of_response:1; }","duration":"226.004212ms","start":"2026-03-31T06:07:16.162112Z","end":"2026-03-31T06:07:16.388116Z","steps":["trace[1291247342] 'process raft request' (duration: 225.639353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:07:16.388298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.691089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:07:16.388409Z","caller":"traceutil/trace.go:171","msg":"trace[1321405475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18913; }","duration":"213.852833ms","start":"2026-03-31T06:07:16.174534Z","end":"2026-03-31T06:07:16.388387Z","steps":["trace[1321405475] 'agreement among raft nodes before linearized reading' (duration: 213.666829ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:07:16.55418Z","caller":"traceutil/trace.go:171","msg":"trace[309181723] transaction","detail":"{read_only:false; response_revision:18915; number_of_response:1; }","duration":"255.312911ms","start":"2026-03-31T06:07:16.298846Z","end":"2026-03-31T06:07:16.554158Z","steps":["trace[309181723] 'process raft request' (duration: 255.101736ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:07:16.554233Z","caller":"traceutil/trace.go:171","msg":"trace[813398493] transaction","detail":"{read_only:false; response_revision:18916; number_of_response:1; }","duration":"241.813929ms","start":"2026-03-31T06:07:16.312411Z","end":"2026-03-31T06:07:16.554225Z","steps":["trace[813398493] 'process raft request' (duration: 241.617845ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:07:16.554374Z","caller":"traceutil/trace.go:171","msg":"trace[486514939] transaction","detail":"{read_only:false; response_revision:18914; number_of_response:1; }","duration":"378.520179ms","start":"2026-03-31T06:07:16.175845Z","end":"2026-03-31T06:07:16.554366Z","steps":["trace[486514939] 'process raft request' (duration: 284.440532ms)","trace[486514939] 'compare' (duration: 93.485532ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:07:16.554461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:07:16.175825Z","time spent":"378.574579ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20984,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:07:16.554521Z","caller":"traceutil/trace.go:171","msg":"trace[1367482965] linearizableReadLoop","detail":"{readStateIndex:19567; appliedIndex:19564; }","duration":"166.302355ms","start":"2026-03-31T06:07:16.388017Z","end":"2026-03-31T06:07:16.55432Z","steps":["trace[1367482965] 'read index received' (duration: 72.435593ms)","trace[1367482965] 'applied index is now lower than readState.Index' (duration: 93.865922ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:07:16.554183Z","caller":"traceutil/trace.go:171","msg":"trace[919273177] transaction","detail":"{read_only:false; response_revision:18917; number_of_response:1; }","duration":"131.22003ms","start":"2026-03-31T06:07:16.422932Z","end":"2026-03-31T06:07:16.554152Z","steps":["trace[919273177] 'process raft request' (duration: 131.161118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:07:16.55494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.736748ms","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-03-31T06:07:16.554998Z","caller":"traceutil/trace.go:171","msg":"trace[434700140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18917; }","duration":"281.83421ms","start":"2026-03-31T06:07:16.27315Z","end":"2026-03-31T06:07:16.554984Z","steps":["trace[434700140] 'agreement among raft nodes before linearized reading' (duration: 281.662506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:07:16.555013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.240969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:07:16.555036Z","caller":"traceutil/trace.go:171","msg":"trace[393071014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18917; }","duration":"165.29871ms","start":"2026-03-31T06:07:16.38973Z","end":"2026-03-31T06:07:16.555029Z","steps":["trace[393071014] 'agreement among raft nodes before linearized reading' (duration: 165.257449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:07:16.98782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.414149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:07:16.98807Z","caller":"traceutil/trace.go:171","msg":"trace[1652307803] linearizableReadLoop","detail":"{readStateIndex:19569; appliedIndex:19568; }","duration":"427.257257ms","start":"2026-03-31T06:07:16.560793Z","end":"2026-03-31T06:07:16.98805Z","steps":["trace[1652307803] 'read index received' (duration: 230.409087ms)","trace[1652307803] 'applied index is now lower than readState.Index' (duration: 196.84628ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:07:16.988174Z","caller":"traceutil/trace.go:171","msg":"trace[54767390] transaction","detail":"{read_only:false; response_revision:18918; number_of_response:1; }","duration":"428.045045ms","start":"2026-03-31T06:07:16.560101Z","end":"2026-03-31T06:07:16.988147Z","steps":["trace[54767390] 'process raft request' (duration: 231.186685ms)","trace[54767390] 'compare' (duration: 196.280906ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:07:16.988347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:07:16.560072Z","time spent":"428.179429ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:07:16.98882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.559728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T06:07:16.988906Z","caller":"traceutil/trace.go:171","msg":"trace[1522015490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18918; }","duration":"359.697681ms","start":"2026-03-31T06:07:16.629172Z","end":"2026-03-31T06:07:16.988869Z","steps":["trace[1522015490] 'agreement among raft nodes before linearized reading' (duration: 359.441075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:07:16.988921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.352695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-6xrdn\" ","response":"range_response_count:1 size:22129"} {"level":"warn","ts":"2026-03-31T06:07:16.988945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:07:16.629148Z","time spent":"359.789103ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T06:07:16.988964Z","caller":"traceutil/trace.go:171","msg":"trace[521177474] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-6xrdn; range_end:; response_count:1; response_revision:18918; }","duration":"166.435057ms","start":"2026-03-31T06:07:16.822517Z","end":"2026-03-31T06:07:16.988952Z","steps":["trace[521177474] 'agreement among raft nodes before linearized reading' (duration: 166.10192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:07:16.988851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.580138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-31T06:07:16.989142Z","caller":"traceutil/trace.go:171","msg":"trace[1348511783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18918; }","duration":"359.850085ms","start":"2026-03-31T06:07:16.629218Z","end":"2026-03-31T06:07:16.989068Z","steps":["trace[1348511783] 'agreement among raft nodes before linearized reading' (duration: 359.412315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:07:16.989259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:07:16.629208Z","time spent":"359.974308ms","remote":"127.0.0.1:49680","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-03-31T06:07:16.989874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.06549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:969549"} {"level":"info","ts":"2026-03-31T06:07:16.990745Z","caller":"traceutil/trace.go:171","msg":"trace[1006655081] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:18918; }","duration":"429.964101ms","start":"2026-03-31T06:07:16.560764Z","end":"2026-03-31T06:07:16.990728Z","steps":["trace[1006655081] 'agreement among raft nodes before linearized reading' (duration: 427.465602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:07:16.990834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:07:16.560756Z","time spent":"430.055783ms","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":97,"response size":969571,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-03-31T06:07:58.13983Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"78a0acde880f698","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T06:07:58.145151Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-03-31T06:07:58.145276Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-03-31T06:08:10.390144Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17056} {"level":"info","ts":"2026-03-31T06:08:10.488016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17056,"took":"95.073981ms","hash":89046195,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":33722368,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-31T06:08:10.488078Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":89046195,"revision":17056,"compact-revision":14657} {"level":"info","ts":"2026-03-31T06:13:10.397228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19579} {"level":"info","ts":"2026-03-31T06:13:10.513727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19579,"took":"112.593436ms","hash":3294409773,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":29978624,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-31T06:13:10.513795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3294409773,"revision":19579,"compact-revision":17056} {"level":"info","ts":"2026-03-31T06:13:24.031749Z","caller":"traceutil/trace.go:171","msg":"trace[510205167] transaction","detail":"{read_only:false; response_revision:21725; number_of_response:1; }","duration":"114.286137ms","start":"2026-03-31T06:13:23.91741Z","end":"2026-03-31T06:13:24.031696Z","steps":["trace[510205167] 'process raft request' (duration: 112.965456ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:13:24.175642Z","caller":"traceutil/trace.go:171","msg":"trace[1047223502] transaction","detail":"{read_only:false; response_revision:21726; number_of_response:1; }","duration":"138.294336ms","start":"2026-03-31T06:13:24.037311Z","end":"2026-03-31T06:13:24.175605Z","steps":["trace[1047223502] 'process raft request' (duration: 138.005019ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:13:24.176371Z","caller":"traceutil/trace.go:171","msg":"trace[1664922790] linearizableReadLoop","detail":"{readStateIndex:22475; appliedIndex:22473; }","duration":"137.556489ms","start":"2026-03-31T06:13:24.038776Z","end":"2026-03-31T06:13:24.176332Z","steps":["trace[1664922790] 'read index received' (duration: 136.838622ms)","trace[1664922790] 'applied index is now lower than readState.Index' (duration: 716.557µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:13:24.176551Z","caller":"traceutil/trace.go:171","msg":"trace[59226110] transaction","detail":"{read_only:false; response_revision:21728; number_of_response:1; }","duration":"138.583704ms","start":"2026-03-31T06:13:24.037954Z","end":"2026-03-31T06:13:24.176537Z","steps":["trace[59226110] 'process raft request' (duration: 138.329308ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:13:24.17665Z","caller":"traceutil/trace.go:171","msg":"trace[1180465127] transaction","detail":"{read_only:false; response_revision:21727; number_of_response:1; }","duration":"138.86417ms","start":"2026-03-31T06:13:24.037648Z","end":"2026-03-31T06:13:24.176512Z","steps":["trace[1180465127] 'process raft request' (duration: 138.42839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:13:24.177168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.354708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-31T06:13:24.177503Z","caller":"traceutil/trace.go:171","msg":"trace[1129925814] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:21728; }","duration":"138.673795ms","start":"2026-03-31T06:13:24.038752Z","end":"2026-03-31T06:13:24.177426Z","steps":["trace[1129925814] 'agreement among raft nodes before linearized reading' (duration: 138.144392ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:13:34.3386Z","caller":"traceutil/trace.go:171","msg":"trace[2011766747] transaction","detail":"{read_only:false; response_revision:21779; number_of_response:1; }","duration":"128.922824ms","start":"2026-03-31T06:13:34.209651Z","end":"2026-03-31T06:13:34.338574Z","steps":["trace[2011766747] 'process raft request' (duration: 128.75522ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:13:34.342817Z","caller":"traceutil/trace.go:171","msg":"trace[2111569185] linearizableReadLoop","detail":"{readStateIndex:22529; appliedIndex:22527; }","duration":"120.3121ms","start":"2026-03-31T06:13:34.222472Z","end":"2026-03-31T06:13:34.342784Z","steps":["trace[2111569185] 'read index received' (duration: 115.921935ms)","trace[2111569185] 'applied index is now lower than readState.Index' (duration: 4.388555ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:13:34.343102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.608796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-31T06:13:34.343102Z","caller":"traceutil/trace.go:171","msg":"trace[1179419446] transaction","detail":"{read_only:false; response_revision:21780; number_of_response:1; }","duration":"122.825749ms","start":"2026-03-31T06:13:34.220239Z","end":"2026-03-31T06:13:34.343065Z","steps":["trace[1179419446] 'process raft request' (duration: 122.348098ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:13:34.343167Z","caller":"traceutil/trace.go:171","msg":"trace[486402586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21780; }","duration":"120.719879ms","start":"2026-03-31T06:13:34.22243Z","end":"2026-03-31T06:13:34.34315Z","steps":["trace[486402586] 'agreement among raft nodes before linearized reading' (duration: 120.476303ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:13:40.494541Z","caller":"traceutil/trace.go:171","msg":"trace[1620307951] transaction","detail":"{read_only:false; response_revision:21809; number_of_response:1; }","duration":"134.803703ms","start":"2026-03-31T06:13:40.3597Z","end":"2026-03-31T06:13:40.494504Z","steps":["trace[1620307951] 'process raft request' (duration: 118.606038ms)","trace[1620307951] 'compare' (duration: 15.904948ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:13:40.494869Z","caller":"traceutil/trace.go:171","msg":"trace[790167011] linearizableReadLoop","detail":"{readStateIndex:22560; appliedIndex:22558; }","duration":"123.254079ms","start":"2026-03-31T06:13:40.371585Z","end":"2026-03-31T06:13:40.494839Z","steps":["trace[790167011] 'read index received' (duration: 106.679565ms)","trace[790167011] 'applied index is now lower than readState.Index' (duration: 16.571984ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:13:40.494951Z","caller":"traceutil/trace.go:171","msg":"trace[70707386] transaction","detail":"{read_only:false; response_revision:21810; number_of_response:1; }","duration":"133.669787ms","start":"2026-03-31T06:13:40.361258Z","end":"2026-03-31T06:13:40.494927Z","steps":["trace[70707386] 'process raft request' (duration: 133.190696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:13:40.495069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.468504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-03-31T06:13:40.495102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.087199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T06:13:40.495133Z","caller":"traceutil/trace.go:171","msg":"trace[1635587160] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:21810; }","duration":"103.159132ms","start":"2026-03-31T06:13:40.391966Z","end":"2026-03-31T06:13:40.495125Z","steps":["trace[1635587160] 'agreement among raft nodes before linearized reading' (duration: 103.023119ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:13:40.495129Z","caller":"traceutil/trace.go:171","msg":"trace[1368516876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21810; }","duration":"123.581526ms","start":"2026-03-31T06:13:40.371532Z","end":"2026-03-31T06:13:40.495114Z","steps":["trace[1368516876] 'agreement among raft nodes before linearized reading' (duration: 123.398022ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:13:40.678175Z","caller":"traceutil/trace.go:171","msg":"trace[1787962885] transaction","detail":"{read_only:false; response_revision:21811; number_of_response:1; }","duration":"178.908171ms","start":"2026-03-31T06:13:40.499229Z","end":"2026-03-31T06:13:40.678137Z","steps":["trace[1787962885] 'process raft request' (duration: 160.273269ms)","trace[1787962885] 'compare' (duration: 18.463078ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:13:40.841717Z","caller":"traceutil/trace.go:171","msg":"trace[347247492] transaction","detail":"{read_only:false; response_revision:21812; number_of_response:1; }","duration":"128.681018ms","start":"2026-03-31T06:13:40.712991Z","end":"2026-03-31T06:13:40.841672Z","steps":["trace[347247492] 'process raft request' (duration: 104.646536ms)","trace[347247492] 'compare' (duration: 23.857178ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:17:43.485382Z","caller":"traceutil/trace.go:171","msg":"trace[1640979378] transaction","detail":"{read_only:false; response_revision:24294; number_of_response:1; }","duration":"269.331901ms","start":"2026-03-31T06:17:43.216012Z","end":"2026-03-31T06:17:43.485344Z","steps":["trace[1640979378] 'process raft request' (duration: 246.833946ms)","trace[1640979378] 'compare' (duration: 21.46846ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:17:43.696466Z","caller":"traceutil/trace.go:171","msg":"trace[707047289] transaction","detail":"{read_only:false; response_revision:24295; number_of_response:1; }","duration":"168.214877ms","start":"2026-03-31T06:17:43.528189Z","end":"2026-03-31T06:17:43.696404Z","steps":["trace[707047289] 'process raft request' (duration: 96.083783ms)","trace[707047289] 'compare' (duration: 71.809336ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:18:10.406509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21655} {"level":"info","ts":"2026-03-31T06:18:10.521079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21655,"took":"109.256976ms","hash":2015313417,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":40120320,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-03-31T06:18:10.521198Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2015313417,"revision":21655,"compact-revision":19579} {"level":"warn","ts":"2026-03-31T06:21:24.900899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.992414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T06:21:24.901013Z","caller":"traceutil/trace.go:171","msg":"trace[1331965207] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:26498; }","duration":"106.129428ms","start":"2026-03-31T06:21:24.794857Z","end":"2026-03-31T06:21:24.900986Z","steps":["trace[1331965207] 'count revisions from in-memory index tree' (duration: 105.907192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:24.901161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.034134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:24.901289Z","caller":"traceutil/trace.go:171","msg":"trace[1042701364] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:26498; }","duration":"132.186157ms","start":"2026-03-31T06:21:24.769082Z","end":"2026-03-31T06:21:24.901268Z","steps":["trace[1042701364] 'count revisions from in-memory index tree' (duration: 131.87887ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:25.411968Z","caller":"traceutil/trace.go:171","msg":"trace[246218642] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"395.317254ms","start":"2026-03-31T06:21:25.016621Z","end":"2026-03-31T06:21:25.411938Z","steps":["trace[246218642] 'process raft request' (duration: 395.136609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:25.412158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:25.016601Z","time spent":"395.450216ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:25.443299Z","caller":"traceutil/trace.go:171","msg":"trace[1368399728] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"400.908617ms","start":"2026-03-31T06:21:25.042368Z","end":"2026-03-31T06:21:25.443276Z","steps":["trace[1368399728] 'process raft request' (duration: 400.695312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:25.443454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:25.042341Z","time spent":"401.03462ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:25.443582Z","caller":"traceutil/trace.go:171","msg":"trace[438207130] linearizableReadLoop","detail":"{readStateIndex:27384; appliedIndex:27382; }","duration":"266.26989ms","start":"2026-03-31T06:21:25.177278Z","end":"2026-03-31T06:21:25.443548Z","steps":["trace[438207130] 'read index received' (duration: 234.466673ms)","trace[438207130] 'applied index is now lower than readState.Index' (duration: 31.800937ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:25.443671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.395535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:25.443704Z","caller":"traceutil/trace.go:171","msg":"trace[164297822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26501; }","duration":"266.427986ms","start":"2026-03-31T06:21:25.177268Z","end":"2026-03-31T06:21:25.443696Z","steps":["trace[164297822] 'agreement among raft nodes before linearized reading' (duration: 266.373084ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:25.443672Z","caller":"traceutil/trace.go:171","msg":"trace[652530038] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"114.078637ms","start":"2026-03-31T06:21:25.329546Z","end":"2026-03-31T06:21:25.443625Z","steps":["trace[652530038] 'process raft request' (duration: 113.685647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:25.443902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.611294ms","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:8"} {"level":"info","ts":"2026-03-31T06:21:25.443987Z","caller":"traceutil/trace.go:171","msg":"trace[1924219714] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26501; }","duration":"225.751716ms","start":"2026-03-31T06:21:25.218219Z","end":"2026-03-31T06:21:25.443971Z","steps":["trace[1924219714] 'agreement among raft nodes before linearized reading' (duration: 225.599343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:25.443982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.203594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-03-31T06:21:25.444075Z","caller":"traceutil/trace.go:171","msg":"trace[1211603934] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26501; }","duration":"170.301076ms","start":"2026-03-31T06:21:25.273752Z","end":"2026-03-31T06:21:25.444053Z","steps":["trace[1211603934] 'agreement among raft nodes before linearized reading' (duration: 170.084881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:25.444432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.354578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-03-31T06:21:25.444511Z","caller":"traceutil/trace.go:171","msg":"trace[807857812] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:26501; }","duration":"102.45663ms","start":"2026-03-31T06:21:25.342037Z","end":"2026-03-31T06:21:25.444494Z","steps":["trace[807857812] 'agreement among raft nodes before linearized reading' (duration: 101.830746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:25.444656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.529639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-31T06:21:25.444727Z","caller":"traceutil/trace.go:171","msg":"trace[1536477305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26501; }","duration":"188.629871ms","start":"2026-03-31T06:21:25.256079Z","end":"2026-03-31T06:21:25.444709Z","steps":["trace[1536477305] 'agreement among raft nodes before linearized reading' (duration: 188.327124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:25.846209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.217033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:21:25.846315Z","caller":"traceutil/trace.go:171","msg":"trace[927021332] linearizableReadLoop","detail":"{readStateIndex:27387; appliedIndex:27386; }","duration":"259.580181ms","start":"2026-03-31T06:21:25.586718Z","end":"2026-03-31T06:21:25.846298Z","steps":["trace[927021332] 'read index received' (duration: 109.907177ms)","trace[927021332] 'applied index is now lower than readState.Index' (duration: 149.671404ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:25.846363Z","caller":"traceutil/trace.go:171","msg":"trace[1640287245] transaction","detail":"{read_only:false; response_revision:26503; number_of_response:1; }","duration":"368.660748ms","start":"2026-03-31T06:21:25.477659Z","end":"2026-03-31T06:21:25.846319Z","steps":["trace[1640287245] 'process raft request' (duration: 219.025225ms)","trace[1640287245] 'compare' (duration: 148.923056ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:25.84651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.781906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-03-31T06:21:25.846544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:25.477638Z","time spent":"368.808662ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:25.846586Z","caller":"traceutil/trace.go:171","msg":"trace[601852989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26503; }","duration":"260.160986ms","start":"2026-03-31T06:21:25.586402Z","end":"2026-03-31T06:21:25.846563Z","steps":["trace[601852989] 'agreement among raft nodes before linearized reading' (duration: 259.961371ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:26.180351Z","caller":"traceutil/trace.go:171","msg":"trace[253640373] linearizableReadLoop","detail":"{readStateIndex:27388; appliedIndex:27387; }","duration":"333.994835ms","start":"2026-03-31T06:21:25.846338Z","end":"2026-03-31T06:21:26.180333Z","steps":["trace[253640373] 'read index received' (duration: 276.138177ms)","trace[253640373] 'applied index is now lower than readState.Index' (duration: 57.855287ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:26.180413Z","caller":"traceutil/trace.go:171","msg":"trace[1132087604] transaction","detail":"{read_only:false; response_revision:26504; number_of_response:1; }","duration":"398.314966ms","start":"2026-03-31T06:21:25.782052Z","end":"2026-03-31T06:21:26.180367Z","steps":["trace[1132087604] 'process raft request' (duration: 340.408037ms)","trace[1132087604] 'compare' (duration: 57.578711ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:26.180543Z","caller":"traceutil/trace.go:171","msg":"trace[108851511] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"329.105117ms","start":"2026-03-31T06:21:25.851429Z","end":"2026-03-31T06:21:26.180534Z","steps":["trace[108851511] 'process raft request' (duration: 328.871511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:26.180706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:25.851402Z","time spent":"329.169239ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:26.18083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.331808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T06:21:26.1809Z","caller":"traceutil/trace.go:171","msg":"trace[1833389682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26506; }","duration":"257.42569ms","start":"2026-03-31T06:21:25.923456Z","end":"2026-03-31T06:21:26.180882Z","steps":["trace[1833389682] 'agreement among raft nodes before linearized reading' (duration: 257.249406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:26.180607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:25.782029Z","time spent":"398.47791ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:26.180939Z","caller":"traceutil/trace.go:171","msg":"trace[716827214] transaction","detail":"{read_only:false; response_revision:26506; number_of_response:1; }","duration":"257.901202ms","start":"2026-03-31T06:21:25.922952Z","end":"2026-03-31T06:21:26.180853Z","steps":["trace[716827214] 'process raft request' (duration: 257.546603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:26.181015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.396188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-03-31T06:21:26.180685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.735318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-31T06:21:26.181203Z","caller":"traceutil/trace.go:171","msg":"trace[753844834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26505; }","duration":"392.264281ms","start":"2026-03-31T06:21:25.788897Z","end":"2026-03-31T06:21:26.181161Z","steps":["trace[753844834] 'agreement among raft nodes before linearized reading' (duration: 391.568205ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:26.181091Z","caller":"traceutil/trace.go:171","msg":"trace[1005075373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26506; }","duration":"254.512351ms","start":"2026-03-31T06:21:25.926565Z","end":"2026-03-31T06:21:26.181077Z","steps":["trace[1005075373] 'agreement among raft nodes before linearized reading' (duration: 254.281516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:26.181424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:25.788887Z","time spent":"392.405084ms","remote":"127.0.0.1:49680","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-03-31T06:21:26.527966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.722036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:21:26.528308Z","caller":"traceutil/trace.go:171","msg":"trace[987222415] linearizableReadLoop","detail":"{readStateIndex:27393; appliedIndex:27391; }","duration":"241.565724ms","start":"2026-03-31T06:21:26.286728Z","end":"2026-03-31T06:21:26.528294Z","steps":["trace[987222415] 'read index received' (duration: 120.446519ms)","trace[987222415] 'applied index is now lower than readState.Index' (duration: 121.117825ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:26.528346Z","caller":"traceutil/trace.go:171","msg":"trace[106823621] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"342.989759ms","start":"2026-03-31T06:21:26.18532Z","end":"2026-03-31T06:21:26.52831Z","steps":["trace[106823621] 'process raft request' (duration: 221.837863ms)","trace[106823621] 'compare' (duration: 120.615083ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:26.528405Z","caller":"traceutil/trace.go:171","msg":"trace[651419454] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"342.809875ms","start":"2026-03-31T06:21:26.185573Z","end":"2026-03-31T06:21:26.528383Z","steps":["trace[651419454] 'process raft request' (duration: 342.665221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:26.528569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.829599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"warn","ts":"2026-03-31T06:21:26.528556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:26.185311Z","time spent":"343.105292ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:26.528651Z","caller":"traceutil/trace.go:171","msg":"trace[1598343004] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26509; }","duration":"241.969893ms","start":"2026-03-31T06:21:26.286664Z","end":"2026-03-31T06:21:26.528634Z","steps":["trace[1598343004] 'agreement among raft nodes before linearized reading' (duration: 241.705446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:26.528671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:26.185555Z","time spent":"342.956718ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:26.575915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.937163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T06:21:26.576061Z","caller":"traceutil/trace.go:171","msg":"trace[1855565336] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:26510; }","duration":"208.137977ms","start":"2026-03-31T06:21:26.367895Z","end":"2026-03-31T06:21:26.576033Z","steps":["trace[1855565336] 'agreement among raft nodes before linearized reading' (duration: 207.867191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:32.554323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.065826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:21:32.554687Z","caller":"traceutil/trace.go:171","msg":"trace[1880503411] linearizableReadLoop","detail":"{readStateIndex:27440; appliedIndex:27438; }","duration":"351.541062ms","start":"2026-03-31T06:21:32.203128Z","end":"2026-03-31T06:21:32.554669Z","steps":["trace[1880503411] 'read index received' (duration: 42.635666ms)","trace[1880503411] 'applied index is now lower than readState.Index' (duration: 308.903655ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:32.554694Z","caller":"traceutil/trace.go:171","msg":"trace[422294139] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"353.206441ms","start":"2026-03-31T06:21:32.20147Z","end":"2026-03-31T06:21:32.554676Z","steps":["trace[422294139] 'process raft request' (duration: 217.700214ms)","trace[422294139] 'compare' (duration: 134.937203ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:32.554793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:32.201455Z","time spent":"353.282913ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:32.554909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.767088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-03-31T06:21:32.554993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.828946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-31T06:21:32.555062Z","caller":"traceutil/trace.go:171","msg":"trace[341264115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26556; }","duration":"248.948828ms","start":"2026-03-31T06:21:32.306094Z","end":"2026-03-31T06:21:32.555042Z","steps":["trace[341264115] 'agreement among raft nodes before linearized reading' (duration: 248.686082ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:32.554983Z","caller":"traceutil/trace.go:171","msg":"trace[902998971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26556; }","duration":"351.866929ms","start":"2026-03-31T06:21:32.203097Z","end":"2026-03-31T06:21:32.554964Z","steps":["trace[902998971] 'agreement among raft nodes before linearized reading' (duration: 351.632094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:32.555143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:32.20309Z","time spent":"352.044563ms","remote":"127.0.0.1:49680","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-03-31T06:21:32.850008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.735271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:21:32.850149Z","caller":"traceutil/trace.go:171","msg":"trace[1816175631] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"291.208515ms","start":"2026-03-31T06:21:32.558912Z","end":"2026-03-31T06:21:32.850121Z","steps":["trace[1816175631] 'process raft request' (duration: 150.191097ms)","trace[1816175631] 'compare' (duration: 140.343992ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:33.050775Z","caller":"traceutil/trace.go:171","msg":"trace[409138200] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"395.380425ms","start":"2026-03-31T06:21:32.655374Z","end":"2026-03-31T06:21:33.050755Z","steps":["trace[409138200] 'process raft request' (duration: 395.325473ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:33.050838Z","caller":"traceutil/trace.go:171","msg":"trace[1895998991] transaction","detail":"{read_only:false; response_revision:26559; number_of_response:1; }","duration":"430.582214ms","start":"2026-03-31T06:21:32.620224Z","end":"2026-03-31T06:21:33.050806Z","steps":["trace[1895998991] 'process raft request' (duration: 430.43503ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:33.050849Z","caller":"traceutil/trace.go:171","msg":"trace[1043804377] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"491.485394ms","start":"2026-03-31T06:21:32.55932Z","end":"2026-03-31T06:21:33.050805Z","steps":["trace[1043804377] 'process raft request' (duration: 453.473088ms)","trace[1043804377] 'compare' (duration: 37.724239ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:33.050932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:32.65535Z","time spent":"395.492818ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:33.05098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:32.620202Z","time spent":"430.696586ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:33.05106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:32.559303Z","time spent":"491.629287ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:33.051182Z","caller":"traceutil/trace.go:171","msg":"trace[2053643679] transaction","detail":"{read_only:false; response_revision:26561; number_of_response:1; }","duration":"391.247597ms","start":"2026-03-31T06:21:32.659925Z","end":"2026-03-31T06:21:33.051173Z","steps":["trace[2053643679] 'process raft request' (duration: 390.801546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:33.051258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:32.659904Z","time spent":"391.293638ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:33.051306Z","caller":"traceutil/trace.go:171","msg":"trace[1444786186] linearizableReadLoop","detail":"{readStateIndex:27443; appliedIndex:27441; }","duration":"483.952334ms","start":"2026-03-31T06:21:32.567343Z","end":"2026-03-31T06:21:33.051295Z","steps":["trace[1444786186] 'read index received' (duration: 141.777746ms)","trace[1444786186] 'applied index is now lower than readState.Index' (duration: 342.173398ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:33.051334Z","caller":"traceutil/trace.go:171","msg":"trace[1361823082] transaction","detail":"{read_only:false; response_revision:26562; number_of_response:1; }","duration":"290.107438ms","start":"2026-03-31T06:21:32.761221Z","end":"2026-03-31T06:21:33.051328Z","steps":["trace[1361823082] 'process raft request' (duration: 289.875263ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:33.05128Z","caller":"traceutil/trace.go:171","msg":"trace[967194808] transaction","detail":"{read_only:false; response_revision:26563; number_of_response:1; }","duration":"101.256481ms","start":"2026-03-31T06:21:32.950004Z","end":"2026-03-31T06:21:33.05126Z","steps":["trace[967194808] 'process raft request' (duration: 101.181299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:33.051448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.087547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T06:21:33.051478Z","caller":"traceutil/trace.go:171","msg":"trace[37177949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26563; }","duration":"484.159329ms","start":"2026-03-31T06:21:32.567311Z","end":"2026-03-31T06:21:33.051471Z","steps":["trace[37177949] 'agreement among raft nodes before linearized reading' (duration: 484.037897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:33.051501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:32.567299Z","time spent":"484.19758ms","remote":"127.0.0.1:49680","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-03-31T06:21:33.051521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.972104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-31T06:21:33.051544Z","caller":"traceutil/trace.go:171","msg":"trace[1330683401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26563; }","duration":"484.016175ms","start":"2026-03-31T06:21:32.567521Z","end":"2026-03-31T06:21:33.051537Z","steps":["trace[1330683401] 'agreement among raft nodes before linearized reading' (duration: 483.917613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:33.051565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:32.567512Z","time spent":"484.046156ms","remote":"127.0.0.1:49680","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":"info","ts":"2026-03-31T06:21:33.234568Z","caller":"traceutil/trace.go:171","msg":"trace[346823542] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"177.667382ms","start":"2026-03-31T06:21:33.056867Z","end":"2026-03-31T06:21:33.234535Z","steps":["trace[346823542] 'process raft request' (duration: 96.939769ms)","trace[346823542] 'compare' (duration: 80.21927ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:33.234622Z","caller":"traceutil/trace.go:171","msg":"trace[526548072] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"177.399615ms","start":"2026-03-31T06:21:33.057192Z","end":"2026-03-31T06:21:33.234592Z","steps":["trace[526548072] 'process raft request' (duration: 177.257272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:33.525588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.661968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:33.525665Z","caller":"traceutil/trace.go:171","msg":"trace[984208295] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26565; }","duration":"163.796951ms","start":"2026-03-31T06:21:33.361851Z","end":"2026-03-31T06:21:33.525648Z","steps":["trace[984208295] 'range keys from in-memory index tree' (duration: 163.40781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:33.525773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.538344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T06:21:33.525879Z","caller":"traceutil/trace.go:171","msg":"trace[643317132] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:26565; }","duration":"100.704088ms","start":"2026-03-31T06:21:33.425149Z","end":"2026-03-31T06:21:33.525853Z","steps":["trace[643317132] 'count revisions from in-memory index tree' (duration: 100.466933ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:33.731757Z","caller":"traceutil/trace.go:171","msg":"trace[560157041] linearizableReadLoop","detail":"{readStateIndex:27451; appliedIndex:27450; }","duration":"217.613612ms","start":"2026-03-31T06:21:33.514117Z","end":"2026-03-31T06:21:33.73173Z","steps":["trace[560157041] 'read index received' (duration: 217.393747ms)","trace[560157041] 'applied index is now lower than readState.Index' (duration: 218.635µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:33.731942Z","caller":"traceutil/trace.go:171","msg":"trace[2119584116] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"292.004554ms","start":"2026-03-31T06:21:33.439884Z","end":"2026-03-31T06:21:33.731888Z","steps":["trace[2119584116] 'process raft request' (duration: 291.656166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:33.731994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.862609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-31T06:21:33.732048Z","caller":"traceutil/trace.go:171","msg":"trace[1001672703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26566; }","duration":"217.977221ms","start":"2026-03-31T06:21:33.51406Z","end":"2026-03-31T06:21:33.732037Z","steps":["trace[1001672703] 'agreement among raft nodes before linearized reading' (duration: 217.811937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:33.732048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.969576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:33.732142Z","caller":"traceutil/trace.go:171","msg":"trace[878072221] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26566; }","duration":"201.119029ms","start":"2026-03-31T06:21:33.531005Z","end":"2026-03-31T06:21:33.732124Z","steps":["trace[878072221] 'agreement among raft nodes before linearized reading' (duration: 200.942895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:33.98587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.288537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.68\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-03-31T06:21:33.985962Z","caller":"traceutil/trace.go:171","msg":"trace[673806954] range","detail":"{range_begin:/registry/masterleases/199.19.213.68; range_end:; response_count:1; response_revision:26567; }","duration":"110.41813ms","start":"2026-03-31T06:21:33.875528Z","end":"2026-03-31T06:21:33.985946Z","steps":["trace[673806954] 'range keys from in-memory index tree' (duration: 110.147134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:34.427055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.539605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-03-31T06:21:34.427168Z","caller":"traceutil/trace.go:171","msg":"trace[527254079] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26569; }","duration":"208.704719ms","start":"2026-03-31T06:21:34.218437Z","end":"2026-03-31T06:21:34.427142Z","steps":["trace[527254079] 'range keys from in-memory index tree' (duration: 208.30124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:34.42722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.3085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:21:34.42733Z","caller":"traceutil/trace.go:171","msg":"trace[752345064] transaction","detail":"{read_only:false; response_revision:26570; number_of_response:1; }","duration":"175.13032ms","start":"2026-03-31T06:21:34.252178Z","end":"2026-03-31T06:21:34.427308Z","steps":["trace[752345064] 'process raft request' (duration: 21.636756ms)","trace[752345064] 'compare' (duration: 152.923871ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:34.702599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.432294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:21:34.702739Z","caller":"traceutil/trace.go:171","msg":"trace[45804041] transaction","detail":"{read_only:false; response_revision:26571; number_of_response:1; }","duration":"142.063693ms","start":"2026-03-31T06:21:34.560652Z","end":"2026-03-31T06:21:34.702716Z","steps":["trace[45804041] 'compare' (duration: 140.094806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:35.005489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.33319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-31T06:21:35.005569Z","caller":"traceutil/trace.go:171","msg":"trace[1419719777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26571; }","duration":"150.454994ms","start":"2026-03-31T06:21:34.855097Z","end":"2026-03-31T06:21:35.005552Z","steps":["trace[1419719777] 'range keys from in-memory index tree' (duration: 150.164396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:35.005853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.01103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:35.005894Z","caller":"traceutil/trace.go:171","msg":"trace[484422078] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:26571; }","duration":"131.058691ms","start":"2026-03-31T06:21:34.874824Z","end":"2026-03-31T06:21:35.005883Z","steps":["trace[484422078] 'count revisions from in-memory index tree' (duration: 130.935888ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:35.258614Z","caller":"traceutil/trace.go:171","msg":"trace[764368725] transaction","detail":"{read_only:false; response_revision:26572; number_of_response:1; }","duration":"244.680846ms","start":"2026-03-31T06:21:35.013911Z","end":"2026-03-31T06:21:35.258592Z","steps":["trace[764368725] 'process raft request' (duration: 244.479041ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:35.301408Z","caller":"traceutil/trace.go:171","msg":"trace[1853318079] transaction","detail":"{read_only:false; response_revision:26573; number_of_response:1; }","duration":"243.55735ms","start":"2026-03-31T06:21:35.057825Z","end":"2026-03-31T06:21:35.301383Z","steps":["trace[1853318079] 'process raft request' (duration: 243.360515ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:35.301785Z","caller":"traceutil/trace.go:171","msg":"trace[1805538946] transaction","detail":"{read_only:false; response_revision:26574; number_of_response:1; }","duration":"243.678173ms","start":"2026-03-31T06:21:35.058092Z","end":"2026-03-31T06:21:35.30177Z","steps":["trace[1805538946] 'process raft request' (duration: 243.241721ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:35.301892Z","caller":"traceutil/trace.go:171","msg":"trace[315109366] transaction","detail":"{read_only:false; response_revision:26575; number_of_response:1; }","duration":"243.317054ms","start":"2026-03-31T06:21:35.058543Z","end":"2026-03-31T06:21:35.30186Z","steps":["trace[315109366] 'process raft request' (duration: 243.067337ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:35.302Z","caller":"traceutil/trace.go:171","msg":"trace[875245985] linearizableReadLoop","detail":"{readStateIndex:27462; appliedIndex:27457; }","duration":"240.984828ms","start":"2026-03-31T06:21:35.060989Z","end":"2026-03-31T06:21:35.301974Z","steps":["trace[875245985] 'read index received' (duration: 197.445451ms)","trace[875245985] 'applied index is now lower than readState.Index' (duration: 43.537707ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:35.302281Z","caller":"traceutil/trace.go:171","msg":"trace[521855422] transaction","detail":"{read_only:false; response_revision:26576; number_of_response:1; }","duration":"243.55417ms","start":"2026-03-31T06:21:35.058708Z","end":"2026-03-31T06:21:35.302262Z","steps":["trace[521855422] 'process raft request' (duration: 243.09797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:35.302304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.738227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:35.302351Z","caller":"traceutil/trace.go:171","msg":"trace[201573134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26576; }","duration":"123.832619ms","start":"2026-03-31T06:21:35.178503Z","end":"2026-03-31T06:21:35.302336Z","steps":["trace[201573134] 'agreement among raft nodes before linearized reading' (duration: 123.734757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:35.302442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.432439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-31T06:21:35.302556Z","caller":"traceutil/trace.go:171","msg":"trace[1372066771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26576; }","duration":"241.600673ms","start":"2026-03-31T06:21:35.060931Z","end":"2026-03-31T06:21:35.302531Z","steps":["trace[1372066771] 'agreement among raft nodes before linearized reading' (duration: 241.268195ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:35.661254Z","caller":"traceutil/trace.go:171","msg":"trace[244944046] transaction","detail":"{read_only:false; response_revision:26577; number_of_response:1; }","duration":"354.229607ms","start":"2026-03-31T06:21:35.306997Z","end":"2026-03-31T06:21:35.661226Z","steps":["trace[244944046] 'process raft request' (duration: 354.019992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:35.661488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:35.306976Z","time spent":"354.41937ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:35.684409Z","caller":"traceutil/trace.go:171","msg":"trace[1096364273] transaction","detail":"{read_only:false; response_revision:26579; number_of_response:1; }","duration":"376.441693ms","start":"2026-03-31T06:21:35.307942Z","end":"2026-03-31T06:21:35.684384Z","steps":["trace[1096364273] 'process raft request' (duration: 376.403062ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:35.684461Z","caller":"traceutil/trace.go:171","msg":"trace[843421153] transaction","detail":"{read_only:false; response_revision:26578; number_of_response:1; }","duration":"376.535966ms","start":"2026-03-31T06:21:35.307888Z","end":"2026-03-31T06:21:35.684424Z","steps":["trace[843421153] 'process raft request' (duration: 376.321111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:35.684552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:35.307917Z","time spent":"376.563937ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:35.684622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:35.307875Z","time spent":"376.663619ms","remote":"127.0.0.1:49680","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-03-31T06:21:35.852557Z","caller":"traceutil/trace.go:171","msg":"trace[1360587346] transaction","detail":"{read_only:false; response_revision:26580; number_of_response:1; }","duration":"114.729712ms","start":"2026-03-31T06:21:35.737784Z","end":"2026-03-31T06:21:35.852514Z","steps":["trace[1360587346] 'process raft request' (duration: 114.532548ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:35.913298Z","caller":"traceutil/trace.go:171","msg":"trace[148895624] transaction","detail":"{read_only:false; response_revision:26581; number_of_response:1; }","duration":"102.492291ms","start":"2026-03-31T06:21:35.810782Z","end":"2026-03-31T06:21:35.913274Z","steps":["trace[148895624] 'process raft request' (duration: 101.57461ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:36.13568Z","caller":"traceutil/trace.go:171","msg":"trace[121661656] transaction","detail":"{read_only:false; response_revision:26583; number_of_response:1; }","duration":"145.207158ms","start":"2026-03-31T06:21:35.990448Z","end":"2026-03-31T06:21:36.135655Z","steps":["trace[121661656] 'process raft request' (duration: 145.016473ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:36.564716Z","caller":"traceutil/trace.go:171","msg":"trace[142250025] transaction","detail":"{read_only:false; response_revision:26584; number_of_response:1; }","duration":"130.511177ms","start":"2026-03-31T06:21:36.434175Z","end":"2026-03-31T06:21:36.564686Z","steps":["trace[142250025] 'process raft request' (duration: 130.340963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:37.615389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.628203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:21:37.615696Z","caller":"traceutil/trace.go:171","msg":"trace[1926902018] linearizableReadLoop","detail":"{readStateIndex:27476; appliedIndex:27472; }","duration":"119.25353ms","start":"2026-03-31T06:21:37.496425Z","end":"2026-03-31T06:21:37.615679Z","steps":["trace[1926902018] 'read index received' (duration: 43.011µs)","trace[1926902018] 'applied index is now lower than readState.Index' (duration: 119.209499ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:37.61565Z","caller":"traceutil/trace.go:171","msg":"trace[734903752] transaction","detail":"{read_only:false; response_revision:26587; number_of_response:1; }","duration":"309.627503ms","start":"2026-03-31T06:21:37.305991Z","end":"2026-03-31T06:21:37.615618Z","steps":["trace[734903752] 'process raft request' (duration: 152.361817ms)","trace[734903752] 'compare' (duration: 156.516098ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:37.615805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.392214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:37.61584Z","caller":"traceutil/trace.go:171","msg":"trace[1976394340] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26590; }","duration":"119.462375ms","start":"2026-03-31T06:21:37.496367Z","end":"2026-03-31T06:21:37.61583Z","steps":["trace[1976394340] 'agreement among raft nodes before linearized reading' (duration: 119.368943ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:37.615924Z","caller":"traceutil/trace.go:171","msg":"trace[1259768935] transaction","detail":"{read_only:false; response_revision:26588; number_of_response:1; }","duration":"309.798567ms","start":"2026-03-31T06:21:37.306109Z","end":"2026-03-31T06:21:37.615908Z","steps":["trace[1259768935] 'process raft request' (duration: 309.376366ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:37.615961Z","caller":"traceutil/trace.go:171","msg":"trace[186375455] transaction","detail":"{read_only:false; response_revision:26589; number_of_response:1; }","duration":"308.274281ms","start":"2026-03-31T06:21:37.307659Z","end":"2026-03-31T06:21:37.615933Z","steps":["trace[186375455] 'process raft request' (duration: 307.938423ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:37.615968Z","caller":"traceutil/trace.go:171","msg":"trace[943285683] transaction","detail":"{read_only:false; response_revision:26590; number_of_response:1; }","duration":"303.949088ms","start":"2026-03-31T06:21:37.311995Z","end":"2026-03-31T06:21:37.615944Z","steps":["trace[943285683] 'process raft request' (duration: 303.643841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:37.616046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:37.306096Z","time spent":"309.88345ms","remote":"127.0.0.1:49680","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-03-31T06:21:37.616101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:37.307651Z","time spent":"308.374633ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:37.616111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:37.31197Z","time spent":"304.069261ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:37.615832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:37.305971Z","time spent":"309.777527ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:44.016794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:43.528146Z","time spent":"488.640457ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-31T06:21:44.020183Z","caller":"traceutil/trace.go:171","msg":"trace[146462498] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"384.122247ms","start":"2026-03-31T06:21:43.636038Z","end":"2026-03-31T06:21:44.02016Z","steps":["trace[146462498] 'process raft request' (duration: 383.964343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.020344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:43.636008Z","time spent":"384.2492ms","remote":"127.0.0.1:49680","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-03-31T06:21:44.041063Z","caller":"traceutil/trace.go:171","msg":"trace[694458700] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"402.037814ms","start":"2026-03-31T06:21:43.639002Z","end":"2026-03-31T06:21:44.04104Z","steps":["trace[694458700] 'process raft request' (duration: 401.947641ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:44.04108Z","caller":"traceutil/trace.go:171","msg":"trace[1991485167] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"402.223428ms","start":"2026-03-31T06:21:43.638818Z","end":"2026-03-31T06:21:44.041041Z","steps":["trace[1991485167] 'process raft request' (duration: 401.986922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.041205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:43.638982Z","time spent":"402.143396ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:44.041453Z","caller":"traceutil/trace.go:171","msg":"trace[1372436082] linearizableReadLoop","detail":"{readStateIndex:27521; appliedIndex:27517; }","duration":"333.8368ms","start":"2026-03-31T06:21:43.707589Z","end":"2026-03-31T06:21:44.041426Z","steps":["trace[1372436082] 'read index received' (duration: 312.3938ms)","trace[1372436082] 'applied index is now lower than readState.Index' (duration: 21.44147ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:44.04148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:43.638784Z","time spent":"402.383012ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:44.0416Z","caller":"traceutil/trace.go:171","msg":"trace[1637524189] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"394.075903ms","start":"2026-03-31T06:21:43.647509Z","end":"2026-03-31T06:21:44.041585Z","steps":["trace[1637524189] 'process raft request' (duration: 393.49162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.0417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.115166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-31T06:21:44.041752Z","caller":"traceutil/trace.go:171","msg":"trace[881721733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26635; }","duration":"334.202038ms","start":"2026-03-31T06:21:43.707535Z","end":"2026-03-31T06:21:44.041737Z","steps":["trace[881721733] 'agreement among raft nodes before linearized reading' (duration: 334.060805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.04175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:43.647478Z","time spent":"394.159586ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:44.041784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:43.707513Z","time spent":"334.26381ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T06:21:44.041887Z","caller":"traceutil/trace.go:171","msg":"trace[2048091944] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"155.186595ms","start":"2026-03-31T06:21:43.886681Z","end":"2026-03-31T06:21:44.041868Z","steps":["trace[2048091944] 'process raft request' (duration: 154.606461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.041918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.240635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:44.042054Z","caller":"traceutil/trace.go:171","msg":"trace[984595236] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26635; }","duration":"202.420851ms","start":"2026-03-31T06:21:43.839612Z","end":"2026-03-31T06:21:44.042033Z","steps":["trace[984595236] 'agreement among raft nodes before linearized reading' (duration: 202.232705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.042192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.34695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-31T06:21:44.04226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.295061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-31T06:21:44.042268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.165676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-31T06:21:44.042323Z","caller":"traceutil/trace.go:171","msg":"trace[965621131] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26635; }","duration":"208.415533ms","start":"2026-03-31T06:21:43.833889Z","end":"2026-03-31T06:21:44.042305Z","steps":["trace[965621131] 'agreement among raft nodes before linearized reading' (duration: 208.317321ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:44.04235Z","caller":"traceutil/trace.go:171","msg":"trace[1176848934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26635; }","duration":"310.266389ms","start":"2026-03-31T06:21:43.732061Z","end":"2026-03-31T06:21:44.042327Z","steps":["trace[1176848934] 'agreement among raft nodes before linearized reading' (duration: 310.008743ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:44.042301Z","caller":"traceutil/trace.go:171","msg":"trace[1309753802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26635; }","duration":"310.499754ms","start":"2026-03-31T06:21:43.731787Z","end":"2026-03-31T06:21:44.042287Z","steps":["trace[1309753802] 'agreement among raft nodes before linearized reading' (duration: 310.316609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.042404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:43.731763Z","time spent":"310.632057ms","remote":"127.0.0.1:49680","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-03-31T06:21:44.042405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:43.732048Z","time spent":"310.34723ms","remote":"127.0.0.1:49680","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-03-31T06:21:44.042389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.283875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.68\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-03-31T06:21:44.041816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.341859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T06:21:44.042598Z","caller":"traceutil/trace.go:171","msg":"trace[1230967876] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26635; }","duration":"311.194411ms","start":"2026-03-31T06:21:43.731385Z","end":"2026-03-31T06:21:44.042579Z","steps":["trace[1230967876] 'agreement among raft nodes before linearized reading' (duration: 310.299569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.042632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:43.731364Z","time spent":"311.257912ms","remote":"127.0.0.1:49856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-31T06:21:44.042833Z","caller":"traceutil/trace.go:171","msg":"trace[663087101] range","detail":"{range_begin:/registry/masterleases/199.19.213.68; range_end:; response_count:1; response_revision:26635; }","duration":"165.43216ms","start":"2026-03-31T06:21:43.877021Z","end":"2026-03-31T06:21:44.042453Z","steps":["trace[663087101] 'agreement among raft nodes before linearized reading' (duration: 165.193194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.588058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.527961ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-31T06:21:44.588145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:44.044925Z","time spent":"543.215895ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-31T06:21:44.601672Z","caller":"traceutil/trace.go:171","msg":"trace[855173663] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"554.604718ms","start":"2026-03-31T06:21:44.047039Z","end":"2026-03-31T06:21:44.601644Z","steps":["trace[855173663] 'process raft request' (duration: 554.466254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.60191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:44.047031Z","time spent":"554.762901ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:44.602045Z","caller":"traceutil/trace.go:171","msg":"trace[158992364] transaction","detail":"{read_only:false; response_revision:26636; number_of_response:1; }","duration":"555.342005ms","start":"2026-03-31T06:21:44.04666Z","end":"2026-03-31T06:21:44.602002Z","steps":["trace[158992364] 'process raft request' (duration: 554.641949ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:44.602224Z","caller":"traceutil/trace.go:171","msg":"trace[342556561] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"555.111921ms","start":"2026-03-31T06:21:44.047089Z","end":"2026-03-31T06:21:44.602201Z","steps":["trace[342556561] 'process raft request' (duration: 554.471205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.602247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:44.046642Z","time spent":"555.492748ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:44.602319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:44.047081Z","time spent":"555.187772ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:21:44.60255Z","caller":"traceutil/trace.go:171","msg":"trace[1727643365] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"555.385746ms","start":"2026-03-31T06:21:44.047135Z","end":"2026-03-31T06:21:44.602521Z","steps":["trace[1727643365] 'process raft request' (duration: 555.035807ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:44.602651Z","caller":"traceutil/trace.go:171","msg":"trace[1653836332] linearizableReadLoop","detail":"{readStateIndex:27527; appliedIndex:27522; }","duration":"553.547263ms","start":"2026-03-31T06:21:44.049083Z","end":"2026-03-31T06:21:44.60263Z","steps":["trace[1653836332] 'read index received' (duration: 268.493744ms)","trace[1653836332] 'applied index is now lower than readState.Index' (duration: 285.052379ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:44.602699Z","caller":"traceutil/trace.go:171","msg":"trace[666409118] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"427.211004ms","start":"2026-03-31T06:21:44.175468Z","end":"2026-03-31T06:21:44.602679Z","steps":["trace[666409118] 'process raft request' (duration: 426.982429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.602774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:44.175439Z","time spent":"427.295426ms","remote":"127.0.0.1:49680","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-03-31T06:21:44.602795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.703696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:44.602826Z","caller":"traceutil/trace.go:171","msg":"trace[1487494190] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26640; }","duration":"553.775198ms","start":"2026-03-31T06:21:44.04904Z","end":"2026-03-31T06:21:44.602816Z","steps":["trace[1487494190] 'agreement among raft nodes before linearized reading' (duration: 553.685616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.602857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:44.049023Z","time spent":"553.826309ms","remote":"127.0.0.1:49610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-03-31T06:21:44.602967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:44.047116Z","time spent":"555.574791ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:44.614079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.74729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:44.614139Z","caller":"traceutil/trace.go:171","msg":"trace[86188142] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26641; }","duration":"163.852982ms","start":"2026-03-31T06:21:44.450272Z","end":"2026-03-31T06:21:44.614125Z","steps":["trace[86188142] 'agreement among raft nodes before linearized reading' (duration: 163.73656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.614116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.408065ms","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:8"} {"level":"info","ts":"2026-03-31T06:21:44.614262Z","caller":"traceutil/trace.go:171","msg":"trace[1720642359] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26641; }","duration":"544.61221ms","start":"2026-03-31T06:21:44.069633Z","end":"2026-03-31T06:21:44.614245Z","steps":["trace[1720642359] 'agreement among raft nodes before linearized reading' (duration: 544.363504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.614314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:44.069591Z","time spent":"544.711893ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-03-31T06:21:44.790668Z","caller":"traceutil/trace.go:171","msg":"trace[701890446] transaction","detail":"{read_only:false; response_revision:26642; number_of_response:1; }","duration":"184.818272ms","start":"2026-03-31T06:21:44.605817Z","end":"2026-03-31T06:21:44.790635Z","steps":["trace[701890446] 'process raft request' (duration: 184.627577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.791074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.550074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:44.791134Z","caller":"traceutil/trace.go:171","msg":"trace[121898661] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26642; }","duration":"181.634296ms","start":"2026-03-31T06:21:44.609489Z","end":"2026-03-31T06:21:44.791123Z","steps":["trace[121898661] 'agreement among raft nodes before linearized reading' (duration: 181.311049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.791216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.192612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:44.791297Z","caller":"traceutil/trace.go:171","msg":"trace[319983948] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26642; }","duration":"183.332546ms","start":"2026-03-31T06:21:44.607944Z","end":"2026-03-31T06:21:44.791276Z","steps":["trace[319983948] 'agreement among raft nodes before linearized reading' (duration: 183.154852ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:44.790614Z","caller":"traceutil/trace.go:171","msg":"trace[985840535] linearizableReadLoop","detail":"{readStateIndex:27530; appliedIndex:27529; }","duration":"176.634567ms","start":"2026-03-31T06:21:44.613959Z","end":"2026-03-31T06:21:44.790594Z","steps":["trace[985840535] 'read index received' (duration: 176.541655ms)","trace[985840535] 'applied index is now lower than readState.Index' (duration: 91.292µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:44.791991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.864388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-31T06:21:44.792084Z","caller":"traceutil/trace.go:171","msg":"trace[397316319] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26643; }","duration":"175.94103ms","start":"2026-03-31T06:21:44.616106Z","end":"2026-03-31T06:21:44.792047Z","steps":["trace[397316319] 'agreement among raft nodes before linearized reading' (duration: 175.710424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.792125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.346255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-03-31T06:21:44.792199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.264319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T06:21:44.792206Z","caller":"traceutil/trace.go:171","msg":"trace[356542447] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26643; }","duration":"112.462578ms","start":"2026-03-31T06:21:44.67973Z","end":"2026-03-31T06:21:44.792193Z","steps":["trace[356542447] 'agreement among raft nodes before linearized reading' (duration: 112.12066ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:44.79225Z","caller":"traceutil/trace.go:171","msg":"trace[1118356804] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:26643; }","duration":"153.371162ms","start":"2026-03-31T06:21:44.638861Z","end":"2026-03-31T06:21:44.792233Z","steps":["trace[1118356804] 'agreement among raft nodes before linearized reading' (duration: 153.252919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:44.792357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.326695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-03-31T06:21:44.792387Z","caller":"traceutil/trace.go:171","msg":"trace[1660110432] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26643; }","duration":"175.378006ms","start":"2026-03-31T06:21:44.617002Z","end":"2026-03-31T06:21:44.79238Z","steps":["trace[1660110432] 'agreement among raft nodes before linearized reading' (duration: 175.270413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:49.612331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.861002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T06:21:49.61276Z","caller":"traceutil/trace.go:171","msg":"trace[774376174] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26735; }","duration":"143.341093ms","start":"2026-03-31T06:21:49.469388Z","end":"2026-03-31T06:21:49.612729Z","steps":["trace[774376174] 'count revisions from in-memory index tree' (duration: 142.76207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:49.612405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.57145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f7bcada1-edfb-4f75-9c27-31a668d8f668\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-03-31T06:21:49.612906Z","caller":"traceutil/trace.go:171","msg":"trace[327593060] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f7bcada1-edfb-4f75-9c27-31a668d8f668; range_end:; response_count:1; response_revision:26735; }","duration":"207.112012ms","start":"2026-03-31T06:21:49.405757Z","end":"2026-03-31T06:21:49.612869Z","steps":["trace[327593060] 'range keys from in-memory index tree' (duration: 206.319773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:49.612576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.984056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-31T06:21:49.613038Z","caller":"traceutil/trace.go:171","msg":"trace[653868474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26735; }","duration":"146.498328ms","start":"2026-03-31T06:21:49.466529Z","end":"2026-03-31T06:21:49.613028Z","steps":["trace[653868474] 'range keys from in-memory index tree' (duration: 145.814182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:49.903987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.885095ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T06:21:49.905249Z","caller":"traceutil/trace.go:171","msg":"trace[1861753578] transaction","detail":"{read_only:false; response_revision:26736; number_of_response:1; }","duration":"287.834585ms","start":"2026-03-31T06:21:49.617382Z","end":"2026-03-31T06:21:49.905217Z","steps":["trace[1861753578] 'process raft request' (duration: 286.743438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:50.001061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.645061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T06:21:50.001139Z","caller":"traceutil/trace.go:171","msg":"trace[474713030] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26736; }","duration":"117.961518ms","start":"2026-03-31T06:21:49.883158Z","end":"2026-03-31T06:21:50.00112Z","steps":["trace[474713030] 'agreement among raft nodes before linearized reading' (duration: 22.025973ms)","trace[474713030] 'count revisions from in-memory index tree' (duration: 95.657338ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:50.316709Z","caller":"traceutil/trace.go:171","msg":"trace[738595424] transaction","detail":"{read_only:false; response_revision:26738; number_of_response:1; }","duration":"255.902373ms","start":"2026-03-31T06:21:50.060773Z","end":"2026-03-31T06:21:50.316675Z","steps":["trace[738595424] 'process raft request' (duration: 245.547187ms)","trace[738595424] 'compare' (duration: 10.201793ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:50.318384Z","caller":"traceutil/trace.go:171","msg":"trace[985655690] linearizableReadLoop","detail":"{readStateIndex:27631; appliedIndex:27626; }","duration":"239.984425ms","start":"2026-03-31T06:21:50.078383Z","end":"2026-03-31T06:21:50.318368Z","steps":["trace[985655690] 'read index received' (duration: 40.32417ms)","trace[985655690] 'applied index is now lower than readState.Index' (duration: 199.659485ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:21:50.318388Z","caller":"traceutil/trace.go:171","msg":"trace[1227290089] transaction","detail":"{read_only:false; response_revision:26739; number_of_response:1; }","duration":"256.795586ms","start":"2026-03-31T06:21:50.061553Z","end":"2026-03-31T06:21:50.318348Z","steps":["trace[1227290089] 'process raft request' (duration: 255.088714ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:50.318477Z","caller":"traceutil/trace.go:171","msg":"trace[1559869314] transaction","detail":"{read_only:false; response_revision:26741; number_of_response:1; }","duration":"255.165996ms","start":"2026-03-31T06:21:50.063301Z","end":"2026-03-31T06:21:50.318468Z","steps":["trace[1559869314] 'process raft request' (duration: 255.028993ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:50.318483Z","caller":"traceutil/trace.go:171","msg":"trace[639910186] transaction","detail":"{read_only:false; response_revision:26740; number_of_response:1; }","duration":"255.586356ms","start":"2026-03-31T06:21:50.062852Z","end":"2026-03-31T06:21:50.318438Z","steps":["trace[639910186] 'process raft request' (duration: 255.419432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:50.318677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.074708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f7bcada1-edfb-4f75-9c27-31a668d8f668\" ","response":"range_response_count:1 size:3643"} {"level":"warn","ts":"2026-03-31T06:21:50.318694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.308352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:50.318726Z","caller":"traceutil/trace.go:171","msg":"trace[84304409] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f7bcada1-edfb-4f75-9c27-31a668d8f668; range_end:; response_count:1; response_revision:26741; }","duration":"227.17863ms","start":"2026-03-31T06:21:50.091531Z","end":"2026-03-31T06:21:50.31871Z","steps":["trace[84304409] 'agreement among raft nodes before linearized reading' (duration: 226.971745ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:50.318729Z","caller":"traceutil/trace.go:171","msg":"trace[616074530] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26741; }","duration":"240.381654ms","start":"2026-03-31T06:21:50.078338Z","end":"2026-03-31T06:21:50.318719Z","steps":["trace[616074530] 'agreement among raft nodes before linearized reading' (duration: 240.310013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:50.319203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.673329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:50.31929Z","caller":"traceutil/trace.go:171","msg":"trace[609993469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26741; }","duration":"145.813652ms","start":"2026-03-31T06:21:50.173457Z","end":"2026-03-31T06:21:50.319271Z","steps":["trace[609993469] 'agreement among raft nodes before linearized reading' (duration: 145.397972ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:52.613991Z","caller":"traceutil/trace.go:171","msg":"trace[98360995] transaction","detail":"{read_only:false; response_revision:26757; number_of_response:1; }","duration":"278.934022ms","start":"2026-03-31T06:21:52.335035Z","end":"2026-03-31T06:21:52.613969Z","steps":["trace[98360995] 'process raft request' (duration: 278.790258ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:52.613999Z","caller":"traceutil/trace.go:171","msg":"trace[1614133163] transaction","detail":"{read_only:false; response_revision:26756; number_of_response:1; }","duration":"287.28849ms","start":"2026-03-31T06:21:52.326673Z","end":"2026-03-31T06:21:52.613961Z","steps":["trace[1614133163] 'process raft request' (duration: 286.981883ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:52.614177Z","caller":"traceutil/trace.go:171","msg":"trace[1330850757] transaction","detail":"{read_only:false; response_revision:26758; number_of_response:1; }","duration":"273.885002ms","start":"2026-03-31T06:21:52.340279Z","end":"2026-03-31T06:21:52.614164Z","steps":["trace[1330850757] 'process raft request' (duration: 273.592674ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:56.963107Z","caller":"traceutil/trace.go:171","msg":"trace[1570546786] transaction","detail":"{read_only:false; response_revision:26817; number_of_response:1; }","duration":"120.360777ms","start":"2026-03-31T06:21:56.842712Z","end":"2026-03-31T06:21:56.963073Z","steps":["trace[1570546786] 'process raft request' (duration: 120.167532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:57.256477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.345767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:21:57.256581Z","caller":"traceutil/trace.go:171","msg":"trace[1712088710] linearizableReadLoop","detail":"{readStateIndex:27710; appliedIndex:27709; }","duration":"403.663143ms","start":"2026-03-31T06:21:56.852904Z","end":"2026-03-31T06:21:57.256567Z","steps":["trace[1712088710] 'read index received' (duration: 110.325187ms)","trace[1712088710] 'applied index is now lower than readState.Index' (duration: 293.336446ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:57.256732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.818996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:57.256806Z","caller":"traceutil/trace.go:171","msg":"trace[922154339] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26818; }","duration":"403.964929ms","start":"2026-03-31T06:21:56.852826Z","end":"2026-03-31T06:21:57.256791Z","steps":["trace[922154339] 'agreement among raft nodes before linearized reading' (duration: 403.817436ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:57.256809Z","caller":"traceutil/trace.go:171","msg":"trace[1333806965] transaction","detail":"{read_only:false; response_revision:26818; number_of_response:1; }","duration":"410.361022ms","start":"2026-03-31T06:21:56.846437Z","end":"2026-03-31T06:21:57.256798Z","steps":["trace[1333806965] 'process raft request' (duration: 226.616696ms)","trace[1333806965] 'compare' (duration: 183.204484ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:57.256844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:56.852796Z","time spent":"404.039861ms","remote":"127.0.0.1:51758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T06:21:57.256871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:21:56.846414Z","time spent":"410.431475ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T06:21:57.256759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.044708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-03-31T06:21:57.25697Z","caller":"traceutil/trace.go:171","msg":"trace[1298875284] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26818; }","duration":"209.255273ms","start":"2026-03-31T06:21:57.047657Z","end":"2026-03-31T06:21:57.256913Z","steps":["trace[1298875284] 'agreement among raft nodes before linearized reading' (duration: 208.991127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:21:57.546886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.059615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:21:57.547294Z","caller":"traceutil/trace.go:171","msg":"trace[463257181] linearizableReadLoop","detail":"{readStateIndex:27711; appliedIndex:27710; }","duration":"140.191559ms","start":"2026-03-31T06:21:57.407076Z","end":"2026-03-31T06:21:57.547268Z","steps":["trace[463257181] 'read index received' (duration: 45.271µs)","trace[463257181] 'applied index is now lower than readState.Index' (duration: 139.876201ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:21:57.547856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.758273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:21:57.547951Z","caller":"traceutil/trace.go:171","msg":"trace[1456678526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26819; }","duration":"140.900756ms","start":"2026-03-31T06:21:57.407031Z","end":"2026-03-31T06:21:57.547932Z","steps":["trace[1456678526] 'agreement among raft nodes before linearized reading' (duration: 140.752313ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:21:57.548414Z","caller":"traceutil/trace.go:171","msg":"trace[315100970] transaction","detail":"{read_only:false; response_revision:26819; number_of_response:1; }","duration":"270.744317ms","start":"2026-03-31T06:21:57.277009Z","end":"2026-03-31T06:21:57.547753Z","steps":["trace[315100970] 'process raft request' (duration: 129.738358ms)","trace[315100970] 'compare' (duration: 139.788609ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:04.087996Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c7d.wal"} {"level":"info","ts":"2026-03-31T06:22:10.618068Z","caller":"traceutil/trace.go:171","msg":"trace[281965734] linearizableReadLoop","detail":"{readStateIndex:27816; appliedIndex:27815; }","duration":"135.66718ms","start":"2026-03-31T06:22:10.482377Z","end":"2026-03-31T06:22:10.618044Z","steps":["trace[281965734] 'read index received' (duration: 135.411344ms)","trace[281965734] 'applied index is now lower than readState.Index' (duration: 254.466µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:10.6181Z","caller":"traceutil/trace.go:171","msg":"trace[801426289] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"149.083459ms","start":"2026-03-31T06:22:10.468987Z","end":"2026-03-31T06:22:10.618071Z","steps":["trace[801426289] 'process raft request' (duration: 148.810373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:10.6183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.900696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:22:10.618346Z","caller":"traceutil/trace.go:171","msg":"trace[2099839100] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:26921; }","duration":"136.003638ms","start":"2026-03-31T06:22:10.482325Z","end":"2026-03-31T06:22:10.618329Z","steps":["trace[2099839100] 'agreement among raft nodes before linearized reading' (duration: 135.845715ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:10.972081Z","caller":"traceutil/trace.go:171","msg":"trace[1006213223] transaction","detail":"{read_only:false; response_revision:26925; number_of_response:1; }","duration":"279.014334ms","start":"2026-03-31T06:22:10.693027Z","end":"2026-03-31T06:22:10.972041Z","steps":["trace[1006213223] 'process raft request' (duration: 194.185204ms)","trace[1006213223] 'compare' (duration: 84.354189ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:10.972224Z","caller":"traceutil/trace.go:171","msg":"trace[1108384074] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"278.999555ms","start":"2026-03-31T06:22:10.693178Z","end":"2026-03-31T06:22:10.972177Z","steps":["trace[1108384074] 'process raft request' (duration: 278.643746ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:10.972654Z","caller":"traceutil/trace.go:171","msg":"trace[104390134] transaction","detail":"{read_only:false; response_revision:26927; number_of_response:1; }","duration":"228.45065ms","start":"2026-03-31T06:22:10.744191Z","end":"2026-03-31T06:22:10.972642Z","steps":["trace[104390134] 'process raft request' (duration: 227.918418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:10.972863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.764654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:22:10.972929Z","caller":"traceutil/trace.go:171","msg":"trace[861063314] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:26927; }","duration":"277.858407ms","start":"2026-03-31T06:22:10.695041Z","end":"2026-03-31T06:22:10.972899Z","steps":["trace[861063314] 'agreement among raft nodes before linearized reading' (duration: 277.746284ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:10.97309Z","caller":"traceutil/trace.go:171","msg":"trace[261742170] linearizableReadLoop","detail":"{readStateIndex:27821; appliedIndex:27818; }","duration":"277.502958ms","start":"2026-03-31T06:22:10.69508Z","end":"2026-03-31T06:22:10.972583Z","steps":["trace[261742170] 'read index received' (duration: 76.408039ms)","trace[261742170] 'applied index is now lower than readState.Index' (duration: 201.093429ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:22:10.973497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.189688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-31T06:22:10.973825Z","caller":"traceutil/trace.go:171","msg":"trace[1096266871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26927; }","duration":"229.558657ms","start":"2026-03-31T06:22:10.744243Z","end":"2026-03-31T06:22:10.973802Z","steps":["trace[1096266871] 'agreement among raft nodes before linearized reading' (duration: 229.074205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:10.973567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.294833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T06:22:10.973989Z","caller":"traceutil/trace.go:171","msg":"trace[1517134619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26927; }","duration":"222.774624ms","start":"2026-03-31T06:22:10.751188Z","end":"2026-03-31T06:22:10.973962Z","steps":["trace[1517134619] 'agreement among raft nodes before linearized reading' (duration: 222.191661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:10.973591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.654278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-31T06:22:10.974094Z","caller":"traceutil/trace.go:171","msg":"trace[971793987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26927; }","duration":"235.191301ms","start":"2026-03-31T06:22:10.738881Z","end":"2026-03-31T06:22:10.974072Z","steps":["trace[971793987] 'agreement among raft nodes before linearized reading' (duration: 234.614808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:10.97362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.875292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-31T06:22:10.974205Z","caller":"traceutil/trace.go:171","msg":"trace[672567774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26927; }","duration":"232.527317ms","start":"2026-03-31T06:22:10.741666Z","end":"2026-03-31T06:22:10.974193Z","steps":["trace[672567774] 'agreement among raft nodes before linearized reading' (duration: 231.80498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:10.973653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.273251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-03-31T06:22:10.974301Z","caller":"traceutil/trace.go:171","msg":"trace[1416698518] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26927; }","duration":"262.172952ms","start":"2026-03-31T06:22:10.712118Z","end":"2026-03-31T06:22:10.974291Z","steps":["trace[1416698518] 'agreement among raft nodes before linearized reading' (duration: 261.179139ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:11.198208Z","caller":"traceutil/trace.go:171","msg":"trace[319624369] transaction","detail":"{read_only:false; response_revision:26929; number_of_response:1; }","duration":"219.076787ms","start":"2026-03-31T06:22:10.979098Z","end":"2026-03-31T06:22:11.198175Z","steps":["trace[319624369] 'process raft request' (duration: 212.693475ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:11.198472Z","caller":"traceutil/trace.go:171","msg":"trace[1140008674] transaction","detail":"{read_only:false; response_revision:26930; number_of_response:1; }","duration":"218.977755ms","start":"2026-03-31T06:22:10.979462Z","end":"2026-03-31T06:22:11.19844Z","steps":["trace[1140008674] 'process raft request' (duration: 218.592086ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:11.198634Z","caller":"traceutil/trace.go:171","msg":"trace[1700597245] transaction","detail":"{read_only:false; response_revision:26931; number_of_response:1; }","duration":"219.068127ms","start":"2026-03-31T06:22:10.979521Z","end":"2026-03-31T06:22:11.198589Z","steps":["trace[1700597245] 'process raft request' (duration: 218.872922ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:11.198949Z","caller":"traceutil/trace.go:171","msg":"trace[1215640978] linearizableReadLoop","detail":"{readStateIndex:27826; appliedIndex:27822; }","duration":"219.000126ms","start":"2026-03-31T06:22:10.979922Z","end":"2026-03-31T06:22:11.198922Z","steps":["trace[1215640978] 'read index received' (duration: 64.109237ms)","trace[1215640978] 'applied index is now lower than readState.Index' (duration: 154.889789ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:11.199037Z","caller":"traceutil/trace.go:171","msg":"trace[854713056] transaction","detail":"{read_only:false; response_revision:26932; number_of_response:1; }","duration":"185.18964ms","start":"2026-03-31T06:22:11.013834Z","end":"2026-03-31T06:22:11.199024Z","steps":["trace[854713056] 'process raft request' (duration: 184.689088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:11.199249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.300023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-03-31T06:22:11.199325Z","caller":"traceutil/trace.go:171","msg":"trace[402490541] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26932; }","duration":"219.414105ms","start":"2026-03-31T06:22:10.979892Z","end":"2026-03-31T06:22:11.199306Z","steps":["trace[402490541] 'agreement among raft nodes before linearized reading' (duration: 219.154099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:13.256145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.20533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:22:13.256274Z","caller":"traceutil/trace.go:171","msg":"trace[1718194622] linearizableReadLoop","detail":"{readStateIndex:27837; appliedIndex:27834; }","duration":"208.439713ms","start":"2026-03-31T06:22:13.047819Z","end":"2026-03-31T06:22:13.256259Z","steps":["trace[1718194622] 'read index received' (duration: 16.619836ms)","trace[1718194622] 'applied index is now lower than readState.Index' (duration: 191.818867ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:13.256334Z","caller":"traceutil/trace.go:171","msg":"trace[1495099841] transaction","detail":"{read_only:false; response_revision:26941; number_of_response:1; }","duration":"276.592897ms","start":"2026-03-31T06:22:12.979732Z","end":"2026-03-31T06:22:13.256325Z","steps":["trace[1495099841] 'process raft request' (duration: 175.09749ms)","trace[1495099841] 'compare' (duration: 101.062807ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:13.256418Z","caller":"traceutil/trace.go:171","msg":"trace[1810223682] transaction","detail":"{read_only:false; response_revision:26942; number_of_response:1; }","duration":"275.883629ms","start":"2026-03-31T06:22:12.980496Z","end":"2026-03-31T06:22:13.25638Z","steps":["trace[1810223682] 'process raft request' (duration: 275.722355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:13.256601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.785181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-31T06:22:13.256627Z","caller":"traceutil/trace.go:171","msg":"trace[1246988112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26942; }","duration":"208.838673ms","start":"2026-03-31T06:22:13.04778Z","end":"2026-03-31T06:22:13.256619Z","steps":["trace[1246988112] 'agreement among raft nodes before linearized reading' (duration: 208.72726ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:18.197242Z","caller":"traceutil/trace.go:171","msg":"trace[1907317069] transaction","detail":"{read_only:false; response_revision:26990; number_of_response:1; }","duration":"119.854675ms","start":"2026-03-31T06:22:18.077369Z","end":"2026-03-31T06:22:18.197223Z","steps":["trace[1907317069] 'process raft request' (duration: 119.753282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:18.482374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.582445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T06:22:18.482686Z","caller":"traceutil/trace.go:171","msg":"trace[1465215882] transaction","detail":"{read_only:false; response_revision:26991; number_of_response:1; }","duration":"343.259895ms","start":"2026-03-31T06:22:18.139378Z","end":"2026-03-31T06:22:18.482638Z","steps":["trace[1465215882] 'process raft request' (duration: 195.266921ms)","trace[1465215882] 'compare' (duration: 147.448311ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:18.482742Z","caller":"traceutil/trace.go:171","msg":"trace[1276301413] linearizableReadLoop","detail":"{readStateIndex:27887; appliedIndex:27885; }","duration":"315.021562ms","start":"2026-03-31T06:22:18.167692Z","end":"2026-03-31T06:22:18.482713Z","steps":["trace[1276301413] 'read index received' (duration: 29.509363ms)","trace[1276301413] 'applied index is now lower than readState.Index' (duration: 285.509369ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:22:18.482893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:22:18.139352Z","time spent":"343.423018ms","remote":"127.0.0.1:49680","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-03-31T06:22:18.482986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.263128ms","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:8"} {"level":"info","ts":"2026-03-31T06:22:18.483009Z","caller":"traceutil/trace.go:171","msg":"trace[1490278771] transaction","detail":"{read_only:false; response_revision:26992; number_of_response:1; }","duration":"200.306681ms","start":"2026-03-31T06:22:18.282674Z","end":"2026-03-31T06:22:18.482981Z","steps":["trace[1490278771] 'process raft request' (duration: 199.85796ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:18.483064Z","caller":"traceutil/trace.go:171","msg":"trace[1698701128] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26992; }","duration":"315.400211ms","start":"2026-03-31T06:22:18.167645Z","end":"2026-03-31T06:22:18.483045Z","steps":["trace[1698701128] 'agreement among raft nodes before linearized reading' (duration: 315.176306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:18.483106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:22:18.167624Z","time spent":"315.473822ms","remote":"127.0.0.1:32842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-03-31T06:22:18.483152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.293853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-03-31T06:22:18.483229Z","caller":"traceutil/trace.go:171","msg":"trace[146807853] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26992; }","duration":"206.377475ms","start":"2026-03-31T06:22:18.276832Z","end":"2026-03-31T06:22:18.483209Z","steps":["trace[146807853] 'agreement among raft nodes before linearized reading' (duration: 206.110629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:18.483749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.40863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:22:18.483812Z","caller":"traceutil/trace.go:171","msg":"trace[1526121973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26992; }","duration":"307.508433ms","start":"2026-03-31T06:22:18.176287Z","end":"2026-03-31T06:22:18.483795Z","steps":["trace[1526121973] 'agreement among raft nodes before linearized reading' (duration: 306.653503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:18.483861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:22:18.176267Z","time spent":"307.581924ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T06:22:19.585302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.941094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-03-31T06:22:19.58537Z","caller":"traceutil/trace.go:171","msg":"trace[275110277] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:27002; }","duration":"130.049357ms","start":"2026-03-31T06:22:19.455304Z","end":"2026-03-31T06:22:19.585354Z","steps":["trace[275110277] 'range keys from in-memory index tree' (duration: 129.796171ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:21.488069Z","caller":"traceutil/trace.go:171","msg":"trace[1990205036] linearizableReadLoop","detail":"{readStateIndex:27910; appliedIndex:27909; }","duration":"124.598607ms","start":"2026-03-31T06:22:21.363447Z","end":"2026-03-31T06:22:21.488046Z","steps":["trace[1990205036] 'read index received' (duration: 50.947303ms)","trace[1990205036] 'applied index is now lower than readState.Index' (duration: 73.650264ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:21.488163Z","caller":"traceutil/trace.go:171","msg":"trace[1761504632] transaction","detail":"{read_only:false; response_revision:27013; number_of_response:1; }","duration":"125.270433ms","start":"2026-03-31T06:22:21.362824Z","end":"2026-03-31T06:22:21.488095Z","steps":["trace[1761504632] 'process raft request' (duration: 51.596068ms)","trace[1761504632] 'compare' (duration: 73.491861ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:22:21.488382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.922715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T06:22:21.488441Z","caller":"traceutil/trace.go:171","msg":"trace[1814734676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27013; }","duration":"124.998197ms","start":"2026-03-31T06:22:21.36343Z","end":"2026-03-31T06:22:21.488428Z","steps":["trace[1814734676] 'agreement among raft nodes before linearized reading' (duration: 124.811733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:21.48857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.016357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-31T06:22:21.488601Z","caller":"traceutil/trace.go:171","msg":"trace[371670433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27013; }","duration":"125.085069ms","start":"2026-03-31T06:22:21.363508Z","end":"2026-03-31T06:22:21.488593Z","steps":["trace[371670433] 'agreement among raft nodes before linearized reading' (duration: 125.011517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:21.488779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.942738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-31T06:22:21.488874Z","caller":"traceutil/trace.go:171","msg":"trace[1490348936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27013; }","duration":"123.078741ms","start":"2026-03-31T06:22:21.365772Z","end":"2026-03-31T06:22:21.488851Z","steps":["trace[1490348936] 'agreement among raft nodes before linearized reading' (duration: 122.813515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:21.725984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.909824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-03-31T06:22:21.726059Z","caller":"traceutil/trace.go:171","msg":"trace[225681908] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27016; }","duration":"138.024408ms","start":"2026-03-31T06:22:21.588018Z","end":"2026-03-31T06:22:21.726042Z","steps":["trace[225681908] 'range keys from in-memory index tree' (duration: 137.670668ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:21.726076Z","caller":"traceutil/trace.go:171","msg":"trace[644427420] linearizableReadLoop","detail":"{readStateIndex:27914; appliedIndex:27913; }","duration":"116.492405ms","start":"2026-03-31T06:22:21.60956Z","end":"2026-03-31T06:22:21.726053Z","steps":["trace[644427420] 'read index received' (duration: 44.857469ms)","trace[644427420] 'applied index is now lower than readState.Index' (duration: 71.633606ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:21.726164Z","caller":"traceutil/trace.go:171","msg":"trace[2097382259] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"134.938544ms","start":"2026-03-31T06:22:21.591208Z","end":"2026-03-31T06:22:21.726147Z","steps":["trace[2097382259] 'process raft request' (duration: 63.276527ms)","trace[2097382259] 'compare' (duration: 71.36843ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T06:22:21.726202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.626078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:22:21.726228Z","caller":"traceutil/trace.go:171","msg":"trace[1693058230] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:27017; }","duration":"116.70399ms","start":"2026-03-31T06:22:21.609515Z","end":"2026-03-31T06:22:21.726219Z","steps":["trace[1693058230] 'agreement among raft nodes before linearized reading' (duration: 116.614468ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:28.789556Z","caller":"traceutil/trace.go:171","msg":"trace[1224225640] transaction","detail":"{read_only:false; response_revision:27064; number_of_response:1; }","duration":"173.325668ms","start":"2026-03-31T06:22:28.616203Z","end":"2026-03-31T06:22:28.789529Z","steps":["trace[1224225640] 'process raft request' (duration: 172.410536ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:28.970196Z","caller":"traceutil/trace.go:171","msg":"trace[8600416] transaction","detail":"{read_only:false; response_revision:27065; number_of_response:1; }","duration":"190.792724ms","start":"2026-03-31T06:22:28.779367Z","end":"2026-03-31T06:22:28.97016Z","steps":["trace[8600416] 'process raft request' (duration: 168.145784ms)","trace[8600416] 'compare' (duration: 22.361143ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:29.199214Z","caller":"traceutil/trace.go:171","msg":"trace[1256069889] transaction","detail":"{read_only:false; response_revision:27066; number_of_response:1; }","duration":"359.603433ms","start":"2026-03-31T06:22:28.839527Z","end":"2026-03-31T06:22:29.19913Z","steps":["trace[1256069889] 'process raft request' (duration: 359.444999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:29.19939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T06:22:28.839504Z","time spent":"359.797258ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T06:22:34.573711Z","caller":"traceutil/trace.go:171","msg":"trace[157451405] transaction","detail":"{read_only:false; response_revision:27108; number_of_response:1; }","duration":"101.836056ms","start":"2026-03-31T06:22:34.471838Z","end":"2026-03-31T06:22:34.573674Z","steps":["trace[157451405] 'process raft request' (duration: 101.634901ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:39.644063Z","caller":"traceutil/trace.go:171","msg":"trace[607650927] transaction","detail":"{read_only:false; response_revision:27154; number_of_response:1; }","duration":"139.931162ms","start":"2026-03-31T06:22:39.504091Z","end":"2026-03-31T06:22:39.644022Z","steps":["trace[607650927] 'process raft request' (duration: 139.195444ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:22:39.762594Z","caller":"traceutil/trace.go:171","msg":"trace[1089422503] transaction","detail":"{read_only:false; response_revision:27156; number_of_response:1; }","duration":"113.010791ms","start":"2026-03-31T06:22:39.649559Z","end":"2026-03-31T06:22:39.76257Z","steps":["trace[1089422503] 'process raft request' (duration: 70.011098ms)","trace[1089422503] 'compare' (duration: 42.698546ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:39.811733Z","caller":"traceutil/trace.go:171","msg":"trace[609580207] linearizableReadLoop","detail":"{readStateIndex:28058; appliedIndex:28056; }","duration":"138.5544ms","start":"2026-03-31T06:22:39.673154Z","end":"2026-03-31T06:22:39.811709Z","steps":["trace[609580207] 'read index received' (duration: 46.433626ms)","trace[609580207] 'applied index is now lower than readState.Index' (duration: 92.119764ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:22:39.81188Z","caller":"traceutil/trace.go:171","msg":"trace[812278663] transaction","detail":"{read_only:false; response_revision:27157; number_of_response:1; }","duration":"161.575518ms","start":"2026-03-31T06:22:39.650255Z","end":"2026-03-31T06:22:39.81183Z","steps":["trace[812278663] 'process raft request' (duration: 161.321551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:39.812123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.840677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T06:22:39.812277Z","caller":"traceutil/trace.go:171","msg":"trace[201380113] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:27157; }","duration":"139.114733ms","start":"2026-03-31T06:22:39.67313Z","end":"2026-03-31T06:22:39.812245Z","steps":["trace[201380113] 'agreement among raft nodes before linearized reading' (duration: 138.745115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:39.829255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.872063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-31T06:22:39.829362Z","caller":"traceutil/trace.go:171","msg":"trace[1683912439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27158; }","duration":"146.045068ms","start":"2026-03-31T06:22:39.68329Z","end":"2026-03-31T06:22:39.829335Z","steps":["trace[1683912439] 'agreement among raft nodes before linearized reading' (duration: 145.765812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T06:22:39.829364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.74277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-31T06:22:39.82942Z","caller":"traceutil/trace.go:171","msg":"trace[1963565373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27158; }","duration":"145.867514ms","start":"2026-03-31T06:22:39.683538Z","end":"2026-03-31T06:22:39.829405Z","steps":["trace[1963565373] 'agreement among raft nodes before linearized reading' (duration: 145.622898ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:23:10.419096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24585} {"level":"info","ts":"2026-03-31T06:23:10.554528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24585,"took":"130.637301ms","hash":389676399,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":35483648,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-03-31T06:23:10.554591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":389676399,"revision":24585,"compact-revision":21655} {"level":"info","ts":"2026-03-31T06:25:56.8906Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"78a0acde880f698","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T06:25:56.900687Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-03-31T06:25:56.900805Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-03-31T06:26:10.616027Z","caller":"traceutil/trace.go:171","msg":"trace[2080082873] transaction","detail":"{read_only:false; response_revision:29251; number_of_response:1; }","duration":"149.298306ms","start":"2026-03-31T06:26:10.466695Z","end":"2026-03-31T06:26:10.615993Z","steps":["trace[2080082873] 'process raft request' (duration: 88.207811ms)","trace[2080082873] 'compare' (duration: 60.956602ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:26:23.313403Z","caller":"traceutil/trace.go:171","msg":"trace[441180340] transaction","detail":"{read_only:false; response_revision:29351; number_of_response:1; }","duration":"106.980707ms","start":"2026-03-31T06:26:23.206388Z","end":"2026-03-31T06:26:23.313368Z","steps":["trace[441180340] 'process raft request' (duration: 40.188037ms)","trace[441180340] 'compare' (duration: 66.596776ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T06:26:23.313585Z","caller":"traceutil/trace.go:171","msg":"trace[1243361202] transaction","detail":"{read_only:false; response_revision:29352; number_of_response:1; }","duration":"106.068985ms","start":"2026-03-31T06:26:23.207484Z","end":"2026-03-31T06:26:23.313553Z","steps":["trace[1243361202] 'process raft request' (duration: 105.837089ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T06:28:10.43367Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27511} {"level":"info","ts":"2026-03-31T06:28:10.576739Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27511,"took":"139.123704ms","hash":158630047,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":34648064,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-03-31T06:28:10.576817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":158630047,"revision":27511,"compact-revision":24585} {"level":"info","ts":"2026-03-31T06:33:10.439911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30262} {"level":"info","ts":"2026-03-31T06:33:10.578831Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30262,"took":"134.323487ms","hash":178246822,"current-db-size-bytes":58146816,"current-db-size":"58 MB","current-db-size-in-use-bytes":31232000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-31T06:33:10.578904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":178246822,"revision":30262,"compact-revision":27511} {"level":"info","ts":"2026-03-31T06:38:10.447662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32519} {"level":"info","ts":"2026-03-31T06:38:10.599204Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32519,"took":"147.187223ms","hash":466114106,"current-db-size-bytes":59187200,"current-db-size":"59 MB","current-db-size-in-use-bytes":30818304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-31T06:38:10.599263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":466114106,"revision":32519,"compact-revision":30262} {"level":"info","ts":"2026-03-31T06:43:10.454762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34754} {"level":"info","ts":"2026-03-31T06:43:10.627041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34754,"took":"166.338582ms","hash":1780681118,"current-db-size-bytes":59641856,"current-db-size":"60 MB","current-db-size-in-use-bytes":31236096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-31T06:43:10.62712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1780681118,"revision":34754,"compact-revision":32519} {"level":"info","ts":"2026-03-31T06:46:55.106732Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"78a0acde880f698","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T06:46:55.111602Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-03-31T06:46:55.111768Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-03-31T06:48:10.467837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37054} {"level":"info","ts":"2026-03-31T06:48:10.58466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37054,"took":"113.637214ms","hash":3744324991,"current-db-size-bytes":60067840,"current-db-size":"60 MB","current-db-size-in-use-bytes":32489472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-31T06:48:10.584713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3744324991,"revision":37054,"compact-revision":34754} {"level":"info","ts":"2026-03-31T06:53:10.474334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39331} {"level":"info","ts":"2026-03-31T06:53:10.606698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39331,"took":"127.888573ms","hash":3365168726,"current-db-size-bytes":60067840,"current-db-size":"60 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-31T06:53:10.606764Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3365168726,"revision":39331,"compact-revision":37054}