{"level":"warn","ts":"2026-06-15T19:04:02.921426Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T19:04:02.921517Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.20: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.20:2380","--initial-cluster=instance=https://199.19.213.20:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.20:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.20:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-15T19:04:02.921763Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T19:04:02.921776Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.20:2380"]} {"level":"info","ts":"2026-06-15T19:04:02.921794Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-15T19:04:02.922438Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"]} {"level":"info","ts":"2026-06-15T19:04:02.922517Z","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.20:2380"],"listen-peer-urls":["https://199.19.213.20:2380"],"advertise-client-urls":["https://199.19.213.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.20:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-15T19:04:02.926566Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.847768ms"} {"level":"info","ts":"2026-06-15T19:04:02.933201Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"185293d8d40ffce0","cluster-id":"cc857afa6e1fa648"} {"level":"info","ts":"2026-06-15T19:04:02.933341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T19:04:02.93339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became follower at term 0"} {"level":"info","ts":"2026-06-15T19:04:02.933412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 185293d8d40ffce0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T19:04:02.933432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became follower at term 1"} {"level":"info","ts":"2026-06-15T19:04:02.933498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=(1752625764480580832)"} {"level":"warn","ts":"2026-06-15T19:04:02.938965Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T19:04:02.940305Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T19:04:02.941387Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-15T19:04:02.943509Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"185293d8d40ffce0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T19:04:02.943704Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"185293d8d40ffce0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T19:04:02.94396Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-15T19:04:02.944062Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-15T19:04:02.944079Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-15T19:04:02.949963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=(1752625764480580832)"} {"level":"info","ts":"2026-06-15T19:04:02.950461Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cc857afa6e1fa648","local-member-id":"185293d8d40ffce0","added-peer-id":"185293d8d40ffce0","added-peer-peer-urls":["https://199.19.213.20:2380"]} {"level":"info","ts":"2026-06-15T19:04:02.953857Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-15T19:04:02.953991Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.20:2380"} {"level":"info","ts":"2026-06-15T19:04:02.954153Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.20:2380"} {"level":"info","ts":"2026-06-15T19:04:02.954292Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"185293d8d40ffce0","initial-advertise-peer-urls":["https://199.19.213.20:2380"],"listen-peer-urls":["https://199.19.213.20:2380"],"advertise-client-urls":["https://199.19.213.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T19:04:02.954358Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T19:04:03.13473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T19:04:03.134792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T19:04:03.13484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 received MsgPreVoteResp from 185293d8d40ffce0 at term 1"} {"level":"info","ts":"2026-06-15T19:04:03.134868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became candidate at term 2"} {"level":"info","ts":"2026-06-15T19:04:03.134885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 received MsgVoteResp from 185293d8d40ffce0 at term 2"} {"level":"info","ts":"2026-06-15T19:04:03.134902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became leader at term 2"} {"level":"info","ts":"2026-06-15T19:04:03.134921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 185293d8d40ffce0 elected leader 185293d8d40ffce0 at term 2"} {"level":"info","ts":"2026-06-15T19:04:03.135963Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:04:03.136118Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"185293d8d40ffce0","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.20:2379]}","request-path":"/0/members/185293d8d40ffce0/attributes","cluster-id":"cc857afa6e1fa648","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T19:04:03.136133Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T19:04:03.136209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T19:04:03.136469Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T19:04:03.1365Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T19:04:03.137081Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cc857afa6e1fa648","local-member-id":"185293d8d40ffce0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:04:03.13719Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:04:03.137226Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:04:03.138793Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T19:04:03.139459Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.20:2379"} {"level":"info","ts":"2026-06-15T19:04:38.775868Z","caller":"traceutil/trace.go:171","msg":"trace[1317468468] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"153.0232ms","start":"2026-06-15T19:04:38.622815Z","end":"2026-06-15T19:04:38.775838Z","steps":["trace[1317468468] 'process raft request' (duration: 152.875346ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:38.782383Z","caller":"traceutil/trace.go:171","msg":"trace[973534827] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"155.819129ms","start":"2026-06-15T19:04:38.626528Z","end":"2026-06-15T19:04:38.782347Z","steps":["trace[973534827] 'process raft request' (duration: 154.413719ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:38.782604Z","caller":"traceutil/trace.go:171","msg":"trace[1099788131] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"155.929731ms","start":"2026-06-15T19:04:38.626663Z","end":"2026-06-15T19:04:38.782593Z","steps":["trace[1099788131] 'process raft request' (duration: 154.44282ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:38.782741Z","caller":"traceutil/trace.go:171","msg":"trace[1206416414] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"146.965691ms","start":"2026-06-15T19:04:38.635766Z","end":"2026-06-15T19:04:38.782731Z","steps":["trace[1206416414] 'process raft request' (duration: 145.393037ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:38.782871Z","caller":"traceutil/trace.go:171","msg":"trace[518440231] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"147.014353ms","start":"2026-06-15T19:04:38.635847Z","end":"2026-06-15T19:04:38.782861Z","steps":["trace[518440231] 'process raft request' (duration: 145.416958ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:38.783004Z","caller":"traceutil/trace.go:171","msg":"trace[1789625066] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"145.377407ms","start":"2026-06-15T19:04:38.637618Z","end":"2026-06-15T19:04:38.782995Z","steps":["trace[1789625066] 'process raft request' (duration: 143.71898ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:38.783166Z","caller":"traceutil/trace.go:171","msg":"trace[22695546] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"144.716279ms","start":"2026-06-15T19:04:38.638439Z","end":"2026-06-15T19:04:38.783155Z","steps":["trace[22695546] 'process raft request' (duration: 142.94721ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:38.783318Z","caller":"traceutil/trace.go:171","msg":"trace[1795104152] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"143.772872ms","start":"2026-06-15T19:04:38.639534Z","end":"2026-06-15T19:04:38.783306Z","steps":["trace[1795104152] 'process raft request' (duration: 141.916101ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:38.783485Z","caller":"traceutil/trace.go:171","msg":"trace[1299602146] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"140.82805ms","start":"2026-06-15T19:04:38.642644Z","end":"2026-06-15T19:04:38.783472Z","steps":["trace[1299602146] 'process raft request' (duration: 138.879886ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:38.783651Z","caller":"traceutil/trace.go:171","msg":"trace[209282383] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"130.661217ms","start":"2026-06-15T19:04:38.652975Z","end":"2026-06-15T19:04:38.783636Z","steps":["trace[209282383] 'process raft request' (duration: 128.63816ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:44.591675Z","caller":"traceutil/trace.go:171","msg":"trace[848865441] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"108.37273ms","start":"2026-06-15T19:04:44.483283Z","end":"2026-06-15T19:04:44.591655Z","steps":["trace[848865441] 'process raft request' (duration: 108.069201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:04:44.655122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.041494ms","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:5"} {"level":"info","ts":"2026-06-15T19:04:44.655218Z","caller":"traceutil/trace.go:171","msg":"trace[314944606] 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:589; }","duration":"104.153927ms","start":"2026-06-15T19:04:44.551046Z","end":"2026-06-15T19:04:44.6552Z","steps":["trace[314944606] 'agreement among raft nodes before linearized reading' (duration: 103.969912ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:44.65533Z","caller":"traceutil/trace.go:171","msg":"trace[496793560] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"100.002606ms","start":"2026-06-15T19:04:44.555298Z","end":"2026-06-15T19:04:44.655301Z","steps":["trace[496793560] 'process raft request' (duration: 98.562638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:04:44.655401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.383593ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:04:44.655461Z","caller":"traceutil/trace.go:171","msg":"trace[927065314] 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:589; }","duration":"104.434984ms","start":"2026-06-15T19:04:44.551009Z","end":"2026-06-15T19:04:44.655444Z","steps":["trace[927065314] 'agreement among raft nodes before linearized reading' (duration: 104.366502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:04:44.655103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.136209ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T19:04:44.655537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.520343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T19:04:44.655586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.243472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:04:44.655606Z","caller":"traceutil/trace.go:171","msg":"trace[193489302] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:589; }","duration":"123.600465ms","start":"2026-06-15T19:04:44.531988Z","end":"2026-06-15T19:04:44.655589Z","steps":["trace[193489302] 'agreement among raft nodes before linearized reading' (duration: 123.523873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:04:44.655324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.251343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:04:44.655615Z","caller":"traceutil/trace.go:171","msg":"trace[340263329] 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:589; }","duration":"103.681944ms","start":"2026-06-15T19:04:44.551882Z","end":"2026-06-15T19:04:44.655564Z","steps":["trace[340263329] 'agreement among raft nodes before linearized reading' (duration: 103.109089ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:44.655681Z","caller":"traceutil/trace.go:171","msg":"trace[989908296] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:589; }","duration":"124.648743ms","start":"2026-06-15T19:04:44.531003Z","end":"2026-06-15T19:04:44.655652Z","steps":["trace[989908296] 'agreement among raft nodes before linearized reading' (duration: 124.238602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:04:44.656003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.898009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:04:44.655666Z","caller":"traceutil/trace.go:171","msg":"trace[2133373909] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:589; }","duration":"139.364495ms","start":"2026-06-15T19:04:44.516278Z","end":"2026-06-15T19:04:44.655643Z","steps":["trace[2133373909] 'agreement among raft nodes before linearized reading' (duration: 139.223032ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:44.656178Z","caller":"traceutil/trace.go:171","msg":"trace[1226310540] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:589; }","duration":"125.025653ms","start":"2026-06-15T19:04:44.53105Z","end":"2026-06-15T19:04:44.656076Z","steps":["trace[1226310540] 'agreement among raft nodes before linearized reading' (duration: 124.623952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:04:44.894042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.493979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:04:44.894153Z","caller":"traceutil/trace.go:171","msg":"trace[132559086] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:590; }","duration":"142.661604ms","start":"2026-06-15T19:04:44.75147Z","end":"2026-06-15T19:04:44.894131Z","steps":["trace[132559086] 'range keys from in-memory index tree' (duration: 142.339295ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:45.041281Z","caller":"traceutil/trace.go:171","msg":"trace[1868578589] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"142.847988ms","start":"2026-06-15T19:04:44.89841Z","end":"2026-06-15T19:04:45.041258Z","steps":["trace[1868578589] 'process raft request' (duration: 142.661623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:04:45.488414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.732288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-15T19:04:45.488427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.124532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-15T19:04:45.488507Z","caller":"traceutil/trace.go:171","msg":"trace[959268706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"359.871352ms","start":"2026-06-15T19:04:45.128619Z","end":"2026-06-15T19:04:45.488491Z","steps":["trace[959268706] 'range keys from in-memory index tree' (duration: 359.564203ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:45.488526Z","caller":"traceutil/trace.go:171","msg":"trace[336017764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"356.282867ms","start":"2026-06-15T19:04:45.132225Z","end":"2026-06-15T19:04:45.488507Z","steps":["trace[336017764] 'range keys from in-memory index tree' (duration: 356.017649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:04:45.488535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:04:45.1286Z","time spent":"359.929643ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T19:04:45.488575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:04:45.132215Z","time spent":"356.350658ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-15T19:04:45.587226Z","caller":"traceutil/trace.go:171","msg":"trace[845439088] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"313.194537ms","start":"2026-06-15T19:04:45.274005Z","end":"2026-06-15T19:04:45.5872Z","steps":["trace[845439088] 'process raft request' (duration: 312.551079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:04:45.587875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:04:45.273975Z","time spent":"313.33041ms","remote":"127.0.0.1:44814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:04:45.59707Z","caller":"traceutil/trace.go:171","msg":"trace[1788399394] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"103.828357ms","start":"2026-06-15T19:04:45.493223Z","end":"2026-06-15T19:04:45.597051Z","steps":["trace[1788399394] 'process raft request' (duration: 103.777056ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:04:45.597167Z","caller":"traceutil/trace.go:171","msg":"trace[2025272385] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"104.407283ms","start":"2026-06-15T19:04:45.492739Z","end":"2026-06-15T19:04:45.597147Z","steps":["trace[2025272385] 'process raft request' (duration: 95.653169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:06:16.204123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.520856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-15T19:06:16.204201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.413487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-15T19:06:16.204148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.294335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:06:16.204257Z","caller":"traceutil/trace.go:171","msg":"trace[1294423645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1825; }","duration":"120.472598ms","start":"2026-06-15T19:06:16.083771Z","end":"2026-06-15T19:06:16.204244Z","steps":["trace[1294423645] 'range keys from in-memory index tree' (duration: 120.263543ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:16.204302Z","caller":"traceutil/trace.go:171","msg":"trace[109269630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1825; }","duration":"143.511271ms","start":"2026-06-15T19:06:16.060761Z","end":"2026-06-15T19:06:16.204272Z","steps":["trace[109269630] 'range keys from in-memory index tree' (duration: 143.215253ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:16.20422Z","caller":"traceutil/trace.go:171","msg":"trace[1849254536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1825; }","duration":"123.6683ms","start":"2026-06-15T19:06:16.080527Z","end":"2026-06-15T19:06:16.204196Z","steps":["trace[1849254536] 'range keys from in-memory index tree' (duration: 123.324851ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:16.491526Z","caller":"traceutil/trace.go:171","msg":"trace[1403568379] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1894; }","duration":"240.557896ms","start":"2026-06-15T19:06:16.250938Z","end":"2026-06-15T19:06:16.491496Z","steps":["trace[1403568379] 'read index received' (duration: 28.443291ms)","trace[1403568379] 'applied index is now lower than readState.Index' (duration: 212.112995ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:06:16.491587Z","caller":"traceutil/trace.go:171","msg":"trace[2147007203] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"282.513865ms","start":"2026-06-15T19:06:16.209035Z","end":"2026-06-15T19:06:16.491549Z","steps":["trace[2147007203] 'process raft request' (duration: 264.899832ms)","trace[2147007203] 'compare' (duration: 17.414488ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:06:16.491833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.847652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-15T19:06:16.491857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.899644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-15T19:06:16.491904Z","caller":"traceutil/trace.go:171","msg":"trace[167991022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1827; }","duration":"238.957795ms","start":"2026-06-15T19:06:16.252929Z","end":"2026-06-15T19:06:16.491887Z","steps":["trace[167991022] 'agreement among raft nodes before linearized reading' (duration: 238.75361ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:16.491944Z","caller":"traceutil/trace.go:171","msg":"trace[437869971] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1827; }","duration":"241.034448ms","start":"2026-06-15T19:06:16.250889Z","end":"2026-06-15T19:06:16.491923Z","steps":["trace[437869971] 'agreement among raft nodes before linearized reading' (duration: 240.756111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:06:16.492256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.575937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.20\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-15T19:06:16.492278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.153414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-15T19:06:16.492426Z","caller":"traceutil/trace.go:171","msg":"trace[2130850966] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1827; }","duration":"189.344409ms","start":"2026-06-15T19:06:16.303047Z","end":"2026-06-15T19:06:16.492391Z","steps":["trace[2130850966] 'agreement among raft nodes before linearized reading' (duration: 188.957669ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:16.492408Z","caller":"traceutil/trace.go:171","msg":"trace[1721280175] range","detail":"{range_begin:/registry/masterleases/199.19.213.20; range_end:; response_count:1; response_revision:1827; }","duration":"137.753142ms","start":"2026-06-15T19:06:16.354606Z","end":"2026-06-15T19:06:16.492359Z","steps":["trace[1721280175] 'agreement among raft nodes before linearized reading' (duration: 137.183667ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:16.626744Z","caller":"traceutil/trace.go:171","msg":"trace[1033524459] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"128.871364ms","start":"2026-06-15T19:06:16.497834Z","end":"2026-06-15T19:06:16.626705Z","steps":["trace[1033524459] 'process raft request' (duration: 128.781081ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:16.626781Z","caller":"traceutil/trace.go:171","msg":"trace[255359373] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"129.348886ms","start":"2026-06-15T19:06:16.497407Z","end":"2026-06-15T19:06:16.626756Z","steps":["trace[255359373] 'process raft request' (duration: 125.353243ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:16.643956Z","caller":"traceutil/trace.go:171","msg":"trace[1656809370] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"127.653503ms","start":"2026-06-15T19:06:16.51628Z","end":"2026-06-15T19:06:16.643933Z","steps":["trace[1656809370] 'process raft request' (duration: 127.484278ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:32.803117Z","caller":"traceutil/trace.go:171","msg":"trace[744277098] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"102.889806ms","start":"2026-06-15T19:06:32.70019Z","end":"2026-06-15T19:06:32.80308Z","steps":["trace[744277098] 'process raft request' (duration: 102.639929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:06:32.803254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.068103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-15T19:06:32.803382Z","caller":"traceutil/trace.go:171","msg":"trace[2025874496] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1959; }","duration":"100.243368ms","start":"2026-06-15T19:06:32.703097Z","end":"2026-06-15T19:06:32.80334Z","steps":["trace[2025874496] 'agreement among raft nodes before linearized reading' (duration: 99.998281ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:06:59.787406Z","caller":"traceutil/trace.go:171","msg":"trace[1213735614] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"120.672888ms","start":"2026-06-15T19:06:59.666702Z","end":"2026-06-15T19:06:59.787375Z","steps":["trace[1213735614] 'process raft request' (duration: 120.528004ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:05.587082Z","caller":"traceutil/trace.go:171","msg":"trace[1690431513] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"113.354109ms","start":"2026-06-15T19:07:05.473703Z","end":"2026-06-15T19:07:05.587057Z","steps":["trace[1690431513] 'process raft request' (duration: 113.196064ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:57.099805Z","caller":"traceutil/trace.go:171","msg":"trace[827554833] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"169.011482ms","start":"2026-06-15T19:07:56.93077Z","end":"2026-06-15T19:07:57.099782Z","steps":["trace[827554833] 'process raft request' (duration: 168.871518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:31.459266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.223728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:08:31.459364Z","caller":"traceutil/trace.go:171","msg":"trace[1456994640] linearizableReadLoop","detail":"{readStateIndex:2911; appliedIndex:2910; }","duration":"221.272265ms","start":"2026-06-15T19:08:31.238077Z","end":"2026-06-15T19:08:31.459349Z","steps":["trace[1456994640] 'read index received' (duration: 106.654777ms)","trace[1456994640] 'applied index is now lower than readState.Index' (duration: 114.615768ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:31.459399Z","caller":"traceutil/trace.go:171","msg":"trace[1288250698] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"221.476161ms","start":"2026-06-15T19:08:31.23789Z","end":"2026-06-15T19:08:31.459366Z","steps":["trace[1288250698] 'process raft request' (duration: 106.907044ms)","trace[1288250698] 'compare' (duration: 114.048782ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:31.459502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.414909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T19:08:31.459553Z","caller":"traceutil/trace.go:171","msg":"trace[1209333387] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2806; }","duration":"221.488892ms","start":"2026-06-15T19:08:31.238051Z","end":"2026-06-15T19:08:31.45954Z","steps":["trace[1209333387] 'agreement among raft nodes before linearized reading' (duration: 221.394909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:31.459825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.300091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-15T19:08:31.460034Z","caller":"traceutil/trace.go:171","msg":"trace[747900048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2806; }","duration":"109.441375ms","start":"2026-06-15T19:08:31.350456Z","end":"2026-06-15T19:08:31.459898Z","steps":["trace[747900048] 'agreement among raft nodes before linearized reading' (duration: 109.055134ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:31.610118Z","caller":"traceutil/trace.go:171","msg":"trace[1997291965] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"146.187207ms","start":"2026-06-15T19:08:31.463889Z","end":"2026-06-15T19:08:31.610076Z","steps":["trace[1997291965] 'process raft request' (duration: 145.250551ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:31.610552Z","caller":"traceutil/trace.go:171","msg":"trace[91635411] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"140.399496ms","start":"2026-06-15T19:08:31.470116Z","end":"2026-06-15T19:08:31.610516Z","steps":["trace[91635411] 'process raft request' (duration: 140.265322ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:10:57.23399Z","caller":"traceutil/trace.go:171","msg":"trace[1934564611] transaction","detail":"{read_only:false; response_revision:3778; number_of_response:1; }","duration":"107.559972ms","start":"2026-06-15T19:10:57.126409Z","end":"2026-06-15T19:10:57.233969Z","steps":["trace[1934564611] 'process raft request' (duration: 107.434299ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:16.130708Z","caller":"traceutil/trace.go:171","msg":"trace[156996827] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"106.102701ms","start":"2026-06-15T19:12:16.024578Z","end":"2026-06-15T19:12:16.130681Z","steps":["trace[156996827] 'process raft request' (duration: 105.870715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:20.060536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.877104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-94eb3cd7-cdc5-44ac-92c9-0f792356f296\" ","response":"range_response_count:1 size:3714"} {"level":"info","ts":"2026-06-15T19:12:20.060605Z","caller":"traceutil/trace.go:171","msg":"trace[535689928] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-94eb3cd7-cdc5-44ac-92c9-0f792356f296; range_end:; response_count:1; response_revision:4996; }","duration":"110.994098ms","start":"2026-06-15T19:12:19.949594Z","end":"2026-06-15T19:12:20.060588Z","steps":["trace[535689928] 'range keys from in-memory index tree' (duration: 110.823863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:20.060801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.627619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-cjqjq-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-06-15T19:12:20.060839Z","caller":"traceutil/trace.go:171","msg":"trace[1601031764] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-cjqjq-tls; range_end:; response_count:1; response_revision:4996; }","duration":"262.702581ms","start":"2026-06-15T19:12:19.798128Z","end":"2026-06-15T19:12:20.060831Z","steps":["trace[1601031764] 'range keys from in-memory index tree' (duration: 262.501036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:20.060923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.368987ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:12:20.06107Z","caller":"traceutil/trace.go:171","msg":"trace[1444547919] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4996; }","duration":"120.527091ms","start":"2026-06-15T19:12:19.940522Z","end":"2026-06-15T19:12:20.06105Z","steps":["trace[1444547919] 'range keys from in-memory index tree' (duration: 120.351307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:20.06107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.873675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-06-15T19:12:20.06116Z","caller":"traceutil/trace.go:171","msg":"trace[199440059] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4996; }","duration":"276.010669ms","start":"2026-06-15T19:12:19.78513Z","end":"2026-06-15T19:12:20.061141Z","steps":["trace[199440059] 'range keys from in-memory index tree' (duration: 275.637299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:20.061206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.505823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786849"} {"level":"info","ts":"2026-06-15T19:12:20.061285Z","caller":"traceutil/trace.go:171","msg":"trace[1190806407] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4996; }","duration":"174.637567ms","start":"2026-06-15T19:12:19.886632Z","end":"2026-06-15T19:12:20.061269Z","steps":["trace[1190806407] 'range keys from in-memory index tree' (duration: 174.04363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:39.079549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.72784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:12:39.079723Z","caller":"traceutil/trace.go:171","msg":"trace[158879169] linearizableReadLoop","detail":"{readStateIndex:5600; appliedIndex:5599; }","duration":"147.588298ms","start":"2026-06-15T19:12:38.93211Z","end":"2026-06-15T19:12:39.079699Z","steps":["trace[158879169] 'read index received' (duration: 23.53166ms)","trace[158879169] 'applied index is now lower than readState.Index' (duration: 124.054568ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:12:39.079789Z","caller":"traceutil/trace.go:171","msg":"trace[1428850516] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"197.350245ms","start":"2026-06-15T19:12:38.882404Z","end":"2026-06-15T19:12:39.079754Z","steps":["trace[1428850516] 'process raft request' (duration: 73.260835ms)","trace[1428850516] 'compare' (duration: 123.589996ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:12:39.0799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.782565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-15T19:12:39.079951Z","caller":"traceutil/trace.go:171","msg":"trace[774027033] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5397; }","duration":"147.853696ms","start":"2026-06-15T19:12:38.932082Z","end":"2026-06-15T19:12:39.079936Z","steps":["trace[774027033] 'agreement among raft nodes before linearized reading' (duration: 147.678951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:39.371329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.440754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:12:39.371451Z","caller":"traceutil/trace.go:171","msg":"trace[872761125] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"286.141339ms","start":"2026-06-15T19:12:39.085289Z","end":"2026-06-15T19:12:39.371431Z","steps":["trace[872761125] 'process raft request' (duration: 129.502299ms)","trace[872761125] 'compare' (duration: 156.309051ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:12:39.395371Z","caller":"traceutil/trace.go:171","msg":"trace[858046141] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"309.964717ms","start":"2026-06-15T19:12:39.085371Z","end":"2026-06-15T19:12:39.395336Z","steps":["trace[858046141] 'process raft request' (duration: 309.739291ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.395401Z","caller":"traceutil/trace.go:171","msg":"trace[1984324030] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"309.981357ms","start":"2026-06-15T19:12:39.08539Z","end":"2026-06-15T19:12:39.395371Z","steps":["trace[1984324030] 'process raft request' (duration: 309.836793ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.395422Z","caller":"traceutil/trace.go:171","msg":"trace[1015819302] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"309.936536ms","start":"2026-06-15T19:12:39.085455Z","end":"2026-06-15T19:12:39.395392Z","steps":["trace[1015819302] 'process raft request' (duration: 309.824613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:39.395529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:12:39.085348Z","time spent":"310.106231ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1623,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T19:12:39.395635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:12:39.08542Z","time spent":"310.0803ms","remote":"127.0.0.1:44888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1131,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:12:39.395749Z","caller":"traceutil/trace.go:171","msg":"trace[1724167624] linearizableReadLoop","detail":"{readStateIndex:5607; appliedIndex:5600; }","duration":"276.021199ms","start":"2026-06-15T19:12:39.119708Z","end":"2026-06-15T19:12:39.395729Z","steps":["trace[1724167624] 'read index received' (duration: 95.13382ms)","trace[1724167624] 'applied index is now lower than readState.Index' (duration: 180.886499ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:12:39.395747Z","caller":"traceutil/trace.go:171","msg":"trace[1607139843] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"309.69321ms","start":"2026-06-15T19:12:39.086024Z","end":"2026-06-15T19:12:39.395718Z","steps":["trace[1607139843] 'process raft request' (duration: 309.312229ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.395803Z","caller":"traceutil/trace.go:171","msg":"trace[506747141] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"306.718478ms","start":"2026-06-15T19:12:39.089063Z","end":"2026-06-15T19:12:39.395781Z","steps":["trace[506747141] 'process raft request' (duration: 306.500232ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.395849Z","caller":"traceutil/trace.go:171","msg":"trace[1054852384] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"305.466093ms","start":"2026-06-15T19:12:39.090369Z","end":"2026-06-15T19:12:39.395835Z","steps":["trace[1054852384] 'process raft request' (duration: 305.304109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:39.39588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:12:39.085814Z","time spent":"310.000689ms","remote":"127.0.0.1:45030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1409,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:12:39.395871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:12:39.085368Z","time spent":"310.103741ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:12:39.395898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:12:39.089052Z","time spent":"306.794089ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3873,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:12:39.395921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:12:39.09036Z","time spent":"305.514784ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:12:39.396077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.365548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d8cf8854-7474-4a3a-a5ee-293087ee0f58\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-15T19:12:39.396122Z","caller":"traceutil/trace.go:171","msg":"trace[482244022] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d8cf8854-7474-4a3a-a5ee-293087ee0f58; range_end:; response_count:1; response_revision:5404; }","duration":"276.439381ms","start":"2026-06-15T19:12:39.11967Z","end":"2026-06-15T19:12:39.396109Z","steps":["trace[482244022] 'agreement among raft nodes before linearized reading' (duration: 276.304837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:39.413076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.685932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-15T19:12:39.413134Z","caller":"traceutil/trace.go:171","msg":"trace[1321093709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5406; }","duration":"147.796755ms","start":"2026-06-15T19:12:39.265326Z","end":"2026-06-15T19:12:39.413123Z","steps":["trace[1321093709] 'agreement among raft nodes before linearized reading' (duration: 147.635251ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.622954Z","caller":"traceutil/trace.go:171","msg":"trace[1070975861] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"220.181475ms","start":"2026-06-15T19:12:39.402758Z","end":"2026-06-15T19:12:39.62294Z","steps":["trace[1070975861] 'process raft request' (duration: 219.912988ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.62299Z","caller":"traceutil/trace.go:171","msg":"trace[1973136559] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"220.475513ms","start":"2026-06-15T19:12:39.402478Z","end":"2026-06-15T19:12:39.622953Z","steps":["trace[1973136559] 'process raft request' (duration: 220.138364ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.622942Z","caller":"traceutil/trace.go:171","msg":"trace[251182125] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"220.977327ms","start":"2026-06-15T19:12:39.40194Z","end":"2026-06-15T19:12:39.622917Z","steps":["trace[251182125] 'process raft request' (duration: 167.824908ms)","trace[251182125] 'compare' (duration: 52.703187ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:12:39.623099Z","caller":"traceutil/trace.go:171","msg":"trace[1018004097] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"220.294009ms","start":"2026-06-15T19:12:39.402776Z","end":"2026-06-15T19:12:39.62307Z","steps":["trace[1018004097] 'process raft request' (duration: 219.933229ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.623558Z","caller":"traceutil/trace.go:171","msg":"trace[442501815] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"220.453563ms","start":"2026-06-15T19:12:39.403095Z","end":"2026-06-15T19:12:39.623548Z","steps":["trace[442501815] 'process raft request' (duration: 219.665381ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.62369Z","caller":"traceutil/trace.go:171","msg":"trace[1923774598] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"220.500154ms","start":"2026-06-15T19:12:39.403128Z","end":"2026-06-15T19:12:39.623628Z","steps":["trace[1923774598] 'process raft request' (duration: 220.33453ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:12:39.623724Z","caller":"traceutil/trace.go:171","msg":"trace[1931295647] linearizableReadLoop","detail":"{readStateIndex:5615; appliedIndex:5609; }","duration":"210.871588ms","start":"2026-06-15T19:12:39.412833Z","end":"2026-06-15T19:12:39.623704Z","steps":["trace[1931295647] 'read index received' (duration: 156.964138ms)","trace[1931295647] 'applied index is now lower than readState.Index' (duration: 53.906ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:12:39.623759Z","caller":"traceutil/trace.go:171","msg":"trace[2131075605] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"207.771633ms","start":"2026-06-15T19:12:39.41591Z","end":"2026-06-15T19:12:39.623681Z","steps":["trace[2131075605] 'process raft request' (duration: 207.67672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:39.623947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.376642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d8cf8854-7474-4a3a-a5ee-293087ee0f58\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-15T19:12:39.62399Z","caller":"traceutil/trace.go:171","msg":"trace[1050128019] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d8cf8854-7474-4a3a-a5ee-293087ee0f58; range_end:; response_count:1; response_revision:5413; }","duration":"224.468684ms","start":"2026-06-15T19:12:39.39951Z","end":"2026-06-15T19:12:39.623978Z","steps":["trace[1050128019] 'agreement among raft nodes before linearized reading' (duration: 224.284019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:12:39.626902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.097197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-15T19:12:39.62696Z","caller":"traceutil/trace.go:171","msg":"trace[339727050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5414; }","duration":"121.20431ms","start":"2026-06-15T19:12:39.505743Z","end":"2026-06-15T19:12:39.626948Z","steps":["trace[339727050] 'agreement among raft nodes before linearized reading' (duration: 121.024065ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:14:03.419726Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2981} {"level":"info","ts":"2026-06-15T19:14:03.490272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2981,"took":"68.931192ms","hash":951755899,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":34377728,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-15T19:14:03.490347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":951755899,"revision":2981,"compact-revision":-1} {"level":"info","ts":"2026-06-15T19:18:29.571044Z","caller":"traceutil/trace.go:171","msg":"trace[1005113044] linearizableReadLoop","detail":"{readStateIndex:8430; appliedIndex:8429; }","duration":"100.098293ms","start":"2026-06-15T19:18:29.47092Z","end":"2026-06-15T19:18:29.571019Z","steps":["trace[1005113044] 'read index received' (duration: 99.178118ms)","trace[1005113044] 'applied index is now lower than readState.Index' (duration: 919.015µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:18:29.571199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.266727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T19:18:29.57123Z","caller":"traceutil/trace.go:171","msg":"trace[1745895994] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8143; }","duration":"100.34606ms","start":"2026-06-15T19:18:29.470874Z","end":"2026-06-15T19:18:29.57122Z","steps":["trace[1745895994] 'agreement among raft nodes before linearized reading' (duration: 100.243177ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:18:29.571496Z","caller":"traceutil/trace.go:171","msg":"trace[762907340] transaction","detail":"{read_only:false; response_revision:8143; number_of_response:1; }","duration":"121.269579ms","start":"2026-06-15T19:18:29.450215Z","end":"2026-06-15T19:18:29.571485Z","steps":["trace[762907340] 'process raft request' (duration: 119.883691ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:18:42.88867Z","caller":"traceutil/trace.go:171","msg":"trace[735906688] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"109.867194ms","start":"2026-06-15T19:18:42.778773Z","end":"2026-06-15T19:18:42.888641Z","steps":["trace[735906688] 'process raft request' (duration: 109.707459ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:03.426165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6156} {"level":"info","ts":"2026-06-15T19:19:03.534312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6156,"took":"106.018807ms","hash":2789295427,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":23457792,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-15T19:19:03.534432Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2789295427,"revision":6156,"compact-revision":2981} {"level":"info","ts":"2026-06-15T19:19:10.036622Z","caller":"traceutil/trace.go:171","msg":"trace[1650738962] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"382.717642ms","start":"2026-06-15T19:19:09.653886Z","end":"2026-06-15T19:19:10.036604Z","steps":["trace[1650738962] 'process raft request' (duration: 382.599739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:19:10.036743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:19:09.653869Z","time spent":"382.809864ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:19:10.037298Z","caller":"traceutil/trace.go:171","msg":"trace[1796711074] linearizableReadLoop","detail":"{readStateIndex:8714; appliedIndex:8712; }","duration":"259.681723ms","start":"2026-06-15T19:19:09.777598Z","end":"2026-06-15T19:19:10.037279Z","steps":["trace[1796711074] 'read index received' (duration: 258.897631ms)","trace[1796711074] 'applied index is now lower than readState.Index' (duration: 782.982µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:19:10.037339Z","caller":"traceutil/trace.go:171","msg":"trace[910083741] transaction","detail":"{read_only:false; response_revision:8415; number_of_response:1; }","duration":"301.315327ms","start":"2026-06-15T19:19:09.736009Z","end":"2026-06-15T19:19:10.037324Z","steps":["trace[910083741] 'process raft request' (duration: 301.174604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:19:10.037473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:19:09.735988Z","time spent":"301.389779ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:19:10.037534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.927481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-15T19:19:10.037585Z","caller":"traceutil/trace.go:171","msg":"trace[810886430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8415; }","duration":"260.005882ms","start":"2026-06-15T19:19:09.777563Z","end":"2026-06-15T19:19:10.037568Z","steps":["trace[810886430] 'agreement among raft nodes before linearized reading' (duration: 259.818097ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:23.145009Z","caller":"traceutil/trace.go:171","msg":"trace[1028555876] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"247.694821ms","start":"2026-06-15T19:19:22.897257Z","end":"2026-06-15T19:19:23.144952Z","steps":["trace[1028555876] 'process raft request' (duration: 177.27187ms)","trace[1028555876] 'compare' (duration: 69.956299ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:19:23.144835Z","caller":"traceutil/trace.go:171","msg":"trace[143933295] linearizableReadLoop","detail":"{readStateIndex:8829; appliedIndex:8828; }","duration":"219.289325ms","start":"2026-06-15T19:19:22.925517Z","end":"2026-06-15T19:19:23.144806Z","steps":["trace[143933295] 'read index received' (duration: 148.995468ms)","trace[143933295] 'applied index is now lower than readState.Index' (duration: 70.291787ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:19:23.1453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.765878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jrlmv\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-06-15T19:19:23.145421Z","caller":"traceutil/trace.go:171","msg":"trace[1432174908] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jrlmv; range_end:; response_count:1; response_revision:8524; }","duration":"219.979884ms","start":"2026-06-15T19:19:22.925421Z","end":"2026-06-15T19:19:23.145401Z","steps":["trace[1432174908] 'agreement among raft nodes before linearized reading' (duration: 219.700406ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:23.295519Z","caller":"traceutil/trace.go:171","msg":"trace[527470665] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8829; }","duration":"150.278782ms","start":"2026-06-15T19:19:23.145212Z","end":"2026-06-15T19:19:23.295491Z","steps":["trace[527470665] 'read index received' (duration: 5.047079ms)","trace[527470665] 'applied index is now lower than readState.Index' (duration: 145.230083ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:19:23.295634Z","caller":"traceutil/trace.go:171","msg":"trace[1504753081] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"209.829822ms","start":"2026-06-15T19:19:23.085765Z","end":"2026-06-15T19:19:23.295595Z","steps":["trace[1504753081] 'process raft request' (duration: 189.060527ms)","trace[1504753081] 'compare' (duration: 20.477627ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:19:23.295703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.196693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-b5xqn\" ","response":"range_response_count:1 size:4647"} {"level":"warn","ts":"2026-06-15T19:19:23.295719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.121233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T19:19:23.295949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.547448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-15T19:19:23.296034Z","caller":"traceutil/trace.go:171","msg":"trace[1857195624] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8527; }","duration":"147.663511ms","start":"2026-06-15T19:19:23.148352Z","end":"2026-06-15T19:19:23.296016Z","steps":["trace[1857195624] 'agreement among raft nodes before linearized reading' (duration: 147.509257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:19:23.296106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.879371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228418"} {"level":"warn","ts":"2026-06-15T19:19:23.296101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.457465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-b5xqn\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-06-15T19:19:23.296138Z","caller":"traceutil/trace.go:171","msg":"trace[132230255] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8527; }","duration":"145.955883ms","start":"2026-06-15T19:19:23.150174Z","end":"2026-06-15T19:19:23.29613Z","steps":["trace[132230255] 'agreement among raft nodes before linearized reading' (duration: 145.726067ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:23.296147Z","caller":"traceutil/trace.go:171","msg":"trace[1431578225] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-b5xqn; range_end:; response_count:1; response_revision:8527; }","duration":"147.525226ms","start":"2026-06-15T19:19:23.148611Z","end":"2026-06-15T19:19:23.296137Z","steps":["trace[1431578225] 'agreement among raft nodes before linearized reading' (duration: 147.399893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:19:23.295771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.567483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414394"} {"level":"info","ts":"2026-06-15T19:19:23.296212Z","caller":"traceutil/trace.go:171","msg":"trace[315156094] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8527; }","duration":"272.031716ms","start":"2026-06-15T19:19:23.024165Z","end":"2026-06-15T19:19:23.296197Z","steps":["trace[315156094] 'agreement among raft nodes before linearized reading' (duration: 271.437979ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:23.295573Z","caller":"traceutil/trace.go:171","msg":"trace[1484282271] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"183.196004ms","start":"2026-06-15T19:19:23.112332Z","end":"2026-06-15T19:19:23.295528Z","steps":["trace[1484282271] 'process raft request' (duration: 183.090412ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:23.295779Z","caller":"traceutil/trace.go:171","msg":"trace[216966180] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-b5xqn; range_end:; response_count:1; response_revision:8527; }","duration":"184.320226ms","start":"2026-06-15T19:19:23.111442Z","end":"2026-06-15T19:19:23.295762Z","steps":["trace[216966180] 'agreement among raft nodes before linearized reading' (duration: 184.09004ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:23.295955Z","caller":"traceutil/trace.go:171","msg":"trace[1708113331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8527; }","duration":"235.395831ms","start":"2026-06-15T19:19:23.060542Z","end":"2026-06-15T19:19:23.295938Z","steps":["trace[1708113331] 'agreement among raft nodes before linearized reading' (duration: 235.01601ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:23.94959Z","caller":"traceutil/trace.go:171","msg":"trace[191592257] linearizableReadLoop","detail":"{readStateIndex:8835; appliedIndex:8834; }","duration":"269.831935ms","start":"2026-06-15T19:19:23.679732Z","end":"2026-06-15T19:19:23.949564Z","steps":["trace[191592257] 'read index received' (duration: 269.389743ms)","trace[191592257] 'applied index is now lower than readState.Index' (duration: 440.412µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:19:23.949643Z","caller":"traceutil/trace.go:171","msg":"trace[1891399822] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"276.780637ms","start":"2026-06-15T19:19:23.672831Z","end":"2026-06-15T19:19:23.949612Z","steps":["trace[1891399822] 'process raft request' (duration: 276.424017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:19:23.949866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.113743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-75848b6c54-vdkjn.18b9574a2be18cb9\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-06-15T19:19:23.94996Z","caller":"traceutil/trace.go:171","msg":"trace[2099809964] range","detail":"{range_begin:/registry/events/openstack/barbican-api-75848b6c54-vdkjn.18b9574a2be18cb9; range_end:; response_count:1; response_revision:8530; }","duration":"270.266147ms","start":"2026-06-15T19:19:23.679674Z","end":"2026-06-15T19:19:23.949941Z","steps":["trace[2099809964] 'agreement among raft nodes before linearized reading' (duration: 269.99329ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:24.092817Z","caller":"traceutil/trace.go:171","msg":"trace[2061234372] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"141.107999ms","start":"2026-06-15T19:19:23.951691Z","end":"2026-06-15T19:19:24.092799Z","steps":["trace[2061234372] 'process raft request' (duration: 141.050788ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:24.093226Z","caller":"traceutil/trace.go:171","msg":"trace[776579065] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"216.109927ms","start":"2026-06-15T19:19:23.877107Z","end":"2026-06-15T19:19:24.093217Z","steps":["trace[776579065] 'process raft request' (duration: 191.489174ms)","trace[776579065] 'compare' (duration: 24.049287ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:19:24.093325Z","caller":"traceutil/trace.go:171","msg":"trace[1889233546] linearizableReadLoop","detail":"{readStateIndex:8836; appliedIndex:8835; }","duration":"143.65156ms","start":"2026-06-15T19:19:23.949655Z","end":"2026-06-15T19:19:24.093306Z","steps":["trace[1889233546] 'read index received' (duration: 118.952535ms)","trace[1889233546] 'applied index is now lower than readState.Index' (duration: 24.696615ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:19:24.093373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.108997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:19:24.093406Z","caller":"traceutil/trace.go:171","msg":"trace[1509973917] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8532; }","duration":"203.20455ms","start":"2026-06-15T19:19:23.890193Z","end":"2026-06-15T19:19:24.093398Z","steps":["trace[1509973917] 'agreement among raft nodes before linearized reading' (duration: 203.114297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:19:24.09341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.563815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:19:24.093442Z","caller":"traceutil/trace.go:171","msg":"trace[1128837956] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:8532; }","duration":"370.626267ms","start":"2026-06-15T19:19:23.722797Z","end":"2026-06-15T19:19:24.093423Z","steps":["trace[1128837956] 'agreement among raft nodes before linearized reading' (duration: 370.570936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:19:24.093464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:19:23.722781Z","time spent":"370.678159ms","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:19:24.093594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.306648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T19:19:24.093615Z","caller":"traceutil/trace.go:171","msg":"trace[287990597] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8532; }","duration":"292.385331ms","start":"2026-06-15T19:19:23.801226Z","end":"2026-06-15T19:19:24.093611Z","steps":["trace[287990597] 'agreement among raft nodes before linearized reading' (duration: 292.342569ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:19:24.787889Z","caller":"traceutil/trace.go:171","msg":"trace[355497913] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"168.409085ms","start":"2026-06-15T19:19:24.61944Z","end":"2026-06-15T19:19:24.787849Z","steps":["trace[355497913] 'process raft request' (duration: 168.134768ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:20:11.279781Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002462.wal"} {"level":"info","ts":"2026-06-15T19:21:31.523251Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"185293d8d40ffce0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-15T19:21:31.529365Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-15T19:21:31.529449Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-15T19:22:53.208533Z","caller":"traceutil/trace.go:171","msg":"trace[1556568801] transaction","detail":"{read_only:false; response_revision:10286; number_of_response:1; }","duration":"105.815837ms","start":"2026-06-15T19:22:53.10269Z","end":"2026-06-15T19:22:53.208506Z","steps":["trace[1556568801] 'process raft request' (duration: 105.369914ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:24:03.432297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8331} {"level":"info","ts":"2026-06-15T19:24:03.511305Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8331,"took":"76.829575ms","hash":4205950731,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":26386432,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-15T19:24:03.511377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4205950731,"revision":8331,"compact-revision":6156} {"level":"info","ts":"2026-06-15T19:29:03.438495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11010} {"level":"info","ts":"2026-06-15T19:29:03.518501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11010,"took":"77.493284ms","hash":1035807396,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":28004352,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-15T19:29:03.518576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1035807396,"revision":11010,"compact-revision":8331} {"level":"info","ts":"2026-06-15T19:30:18.288336Z","caller":"traceutil/trace.go:171","msg":"trace[1597118696] linearizableReadLoop","detail":"{readStateIndex:14004; appliedIndex:14003; }","duration":"229.656824ms","start":"2026-06-15T19:30:18.05866Z","end":"2026-06-15T19:30:18.288317Z","steps":["trace[1597118696] 'read index received' (duration: 229.52227ms)","trace[1597118696] 'applied index is now lower than readState.Index' (duration: 133.814µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:30:18.288378Z","caller":"traceutil/trace.go:171","msg":"trace[1854019259] transaction","detail":"{read_only:false; response_revision:13541; number_of_response:1; }","duration":"236.421782ms","start":"2026-06-15T19:30:18.051924Z","end":"2026-06-15T19:30:18.288346Z","steps":["trace[1854019259] 'process raft request' (duration: 236.253787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:30:18.288537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.828889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:30:18.288571Z","caller":"traceutil/trace.go:171","msg":"trace[1803139790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13541; }","duration":"229.941702ms","start":"2026-06-15T19:30:18.058619Z","end":"2026-06-15T19:30:18.288561Z","steps":["trace[1803139790] 'agreement among raft nodes before linearized reading' (duration: 229.814488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:30:18.289002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.477802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jrlmv\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-06-15T19:30:18.289069Z","caller":"traceutil/trace.go:171","msg":"trace[997625913] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jrlmv; range_end:; response_count:1; response_revision:13541; }","duration":"155.594935ms","start":"2026-06-15T19:30:18.133446Z","end":"2026-06-15T19:30:18.289041Z","steps":["trace[997625913] 'agreement among raft nodes before linearized reading' (duration: 155.121742ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:30:27.650318Z","caller":"traceutil/trace.go:171","msg":"trace[259902323] transaction","detail":"{read_only:false; response_revision:13608; number_of_response:1; }","duration":"117.914272ms","start":"2026-06-15T19:30:27.53238Z","end":"2026-06-15T19:30:27.650295Z","steps":["trace[259902323] 'process raft request' (duration: 117.803439ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:30:33.619073Z","caller":"traceutil/trace.go:171","msg":"trace[132456384] transaction","detail":"{read_only:false; response_revision:13638; number_of_response:1; }","duration":"162.319051ms","start":"2026-06-15T19:30:33.456724Z","end":"2026-06-15T19:30:33.619043Z","steps":["trace[132456384] 'process raft request' (duration: 161.394816ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:30:33.619104Z","caller":"traceutil/trace.go:171","msg":"trace[853439027] transaction","detail":"{read_only:false; response_revision:13639; number_of_response:1; }","duration":"154.723451ms","start":"2026-06-15T19:30:33.464319Z","end":"2026-06-15T19:30:33.619043Z","steps":["trace[853439027] 'process raft request' (duration: 154.589448ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:30:34.258847Z","caller":"traceutil/trace.go:171","msg":"trace[1513989260] transaction","detail":"{read_only:false; response_revision:13642; number_of_response:1; }","duration":"576.10335ms","start":"2026-06-15T19:30:33.682683Z","end":"2026-06-15T19:30:34.258787Z","steps":["trace[1513989260] 'process raft request' (duration: 575.919285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:30:34.259185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:30:33.682666Z","time spent":"576.266544ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:30:34.299879Z","caller":"traceutil/trace.go:171","msg":"trace[1970960482] linearizableReadLoop","detail":"{readStateIndex:14111; appliedIndex:14109; }","duration":"517.986632ms","start":"2026-06-15T19:30:33.781864Z","end":"2026-06-15T19:30:34.299851Z","steps":["trace[1970960482] 'read index received' (duration: 476.819893ms)","trace[1970960482] 'applied index is now lower than readState.Index' (duration: 41.165379ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:30:34.299937Z","caller":"traceutil/trace.go:171","msg":"trace[413390690] transaction","detail":"{read_only:false; response_revision:13643; number_of_response:1; }","duration":"595.146147ms","start":"2026-06-15T19:30:33.704759Z","end":"2026-06-15T19:30:34.299905Z","steps":["trace[413390690] 'process raft request' (duration: 584.949505ms)","trace[413390690] 'compare' (duration: 10.008207ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:30:34.300099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:30:33.704728Z","time spent":"595.27226ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:30:34.300154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.2792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-15T19:30:34.300223Z","caller":"traceutil/trace.go:171","msg":"trace[318131842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13643; }","duration":"518.387643ms","start":"2026-06-15T19:30:33.781816Z","end":"2026-06-15T19:30:34.300203Z","steps":["trace[318131842] 'agreement among raft nodes before linearized reading' (duration: 518.189637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:30:34.300277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.340972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T19:30:34.300278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:30:33.781801Z","time spent":"518.462846ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-15T19:30:34.300316Z","caller":"traceutil/trace.go:171","msg":"trace[1835243289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13643; }","duration":"239.415414ms","start":"2026-06-15T19:30:34.06089Z","end":"2026-06-15T19:30:34.300306Z","steps":["trace[1835243289] 'agreement among raft nodes before linearized reading' (duration: 239.356272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:32:04.151052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.413178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-15T19:32:04.151157Z","caller":"traceutil/trace.go:171","msg":"trace[1402843276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14659; }","duration":"113.582583ms","start":"2026-06-15T19:32:04.037553Z","end":"2026-06-15T19:32:04.151136Z","steps":["trace[1402843276] 'range keys from in-memory index tree' (duration: 113.244694ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:32:04.269976Z","caller":"traceutil/trace.go:171","msg":"trace[682226476] transaction","detail":"{read_only:false; response_revision:14660; number_of_response:1; }","duration":"114.962291ms","start":"2026-06-15T19:32:04.154992Z","end":"2026-06-15T19:32:04.269954Z","steps":["trace[682226476] 'process raft request' (duration: 114.631052ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:32:05.275324Z","caller":"traceutil/trace.go:171","msg":"trace[853025025] transaction","detail":"{read_only:false; response_revision:14665; number_of_response:1; }","duration":"130.485621ms","start":"2026-06-15T19:32:05.144814Z","end":"2026-06-15T19:32:05.2753Z","steps":["trace[853025025] 'process raft request' (duration: 130.340497ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:32:05.451218Z","caller":"traceutil/trace.go:171","msg":"trace[1770504365] transaction","detail":"{read_only:false; response_revision:14666; number_of_response:1; }","duration":"170.640592ms","start":"2026-06-15T19:32:05.280536Z","end":"2026-06-15T19:32:05.451177Z","steps":["trace[1770504365] 'process raft request' (duration: 140.482227ms)","trace[1770504365] 'compare' (duration: 29.444985ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:34:03.451233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13049} {"level":"info","ts":"2026-06-15T19:34:03.535817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13049,"took":"81.497442ms","hash":703296892,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":31064064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-15T19:34:03.535886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":703296892,"revision":13049,"compact-revision":11010} {"level":"info","ts":"2026-06-15T19:37:32.446047Z","caller":"traceutil/trace.go:171","msg":"trace[200051113] linearizableReadLoop","detail":"{readStateIndex:17721; appliedIndex:17720; }","duration":"132.352577ms","start":"2026-06-15T19:37:32.313657Z","end":"2026-06-15T19:37:32.44601Z","steps":["trace[200051113] 'read index received' (duration: 132.036289ms)","trace[200051113] 'applied index is now lower than readState.Index' (duration: 315.118µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:37:32.446298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.620184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-15T19:37:32.446352Z","caller":"traceutil/trace.go:171","msg":"trace[1761828962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17139; }","duration":"132.732677ms","start":"2026-06-15T19:37:32.313605Z","end":"2026-06-15T19:37:32.446338Z","steps":["trace[1761828962] 'agreement among raft nodes before linearized reading' (duration: 132.535162ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:37:32.446148Z","caller":"traceutil/trace.go:171","msg":"trace[1840757242] transaction","detail":"{read_only:false; response_revision:17139; number_of_response:1; }","duration":"142.159636ms","start":"2026-06-15T19:37:32.303952Z","end":"2026-06-15T19:37:32.446112Z","steps":["trace[1840757242] 'process raft request' (duration: 141.849008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:37:37.959298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.433579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:37:37.95942Z","caller":"traceutil/trace.go:171","msg":"trace[2009332454] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:17173; }","duration":"394.575033ms","start":"2026-06-15T19:37:37.56481Z","end":"2026-06-15T19:37:37.959385Z","steps":["trace[2009332454] 'count revisions from in-memory index tree' (duration: 394.341226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:37:37.959477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:37:37.564734Z","time spent":"394.727397ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:37:37.959637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.240914ms","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:6"} {"level":"info","ts":"2026-06-15T19:37:37.959771Z","caller":"traceutil/trace.go:171","msg":"trace[675870226] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:17173; }","duration":"151.394619ms","start":"2026-06-15T19:37:37.808353Z","end":"2026-06-15T19:37:37.959747Z","steps":["trace[675870226] 'count revisions from in-memory index tree' (duration: 151.102181ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:37:38.20472Z","caller":"traceutil/trace.go:171","msg":"trace[1205382098] transaction","detail":"{read_only:false; response_revision:17174; number_of_response:1; }","duration":"240.300485ms","start":"2026-06-15T19:37:37.964401Z","end":"2026-06-15T19:37:38.204701Z","steps":["trace[1205382098] 'process raft request' (duration: 240.200083ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:37:38.20976Z","caller":"traceutil/trace.go:171","msg":"trace[781190977] linearizableReadLoop","detail":"{readStateIndex:17758; appliedIndex:17757; }","duration":"149.2448ms","start":"2026-06-15T19:37:38.060507Z","end":"2026-06-15T19:37:38.209752Z","steps":["trace[781190977] 'read index received' (duration: 144.533101ms)","trace[781190977] 'applied index is now lower than readState.Index' (duration: 4.711409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:37:38.209846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.330473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:37:38.20987Z","caller":"traceutil/trace.go:171","msg":"trace[1217520259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17175; }","duration":"149.441776ms","start":"2026-06-15T19:37:38.060422Z","end":"2026-06-15T19:37:38.209863Z","steps":["trace[1217520259] 'agreement among raft nodes before linearized reading' (duration: 149.387134ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:37:38.20986Z","caller":"traceutil/trace.go:171","msg":"trace[1265267034] transaction","detail":"{read_only:false; response_revision:17175; number_of_response:1; }","duration":"173.455814ms","start":"2026-06-15T19:37:38.036363Z","end":"2026-06-15T19:37:38.209819Z","steps":["trace[1265267034] 'process raft request' (duration: 173.347891ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:39:03.458255Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15489} {"level":"info","ts":"2026-06-15T19:39:03.554612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15489,"took":"92.639929ms","hash":4006094070,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":34066432,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-15T19:39:03.554682Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4006094070,"revision":15489,"compact-revision":13049} {"level":"info","ts":"2026-06-15T19:41:51.480783Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"185293d8d40ffce0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-15T19:41:51.485286Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-15T19:41:51.485375Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-15T19:42:48.626198Z","caller":"traceutil/trace.go:171","msg":"trace[208867495] transaction","detail":"{read_only:false; response_revision:19665; number_of_response:1; }","duration":"100.500731ms","start":"2026-06-15T19:42:48.525673Z","end":"2026-06-15T19:42:48.626173Z","steps":["trace[208867495] 'process raft request' (duration: 100.358357ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:43:15.224429Z","caller":"traceutil/trace.go:171","msg":"trace[894910002] transaction","detail":"{read_only:false; response_revision:19799; number_of_response:1; }","duration":"105.601804ms","start":"2026-06-15T19:43:15.118793Z","end":"2026-06-15T19:43:15.224394Z","steps":["trace[894910002] 'process raft request' (duration: 105.396488ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:44:03.464036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18067} {"level":"info","ts":"2026-06-15T19:44:03.585669Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18067,"took":"118.005556ms","hash":419755105,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":30228480,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-15T19:44:03.585836Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":419755105,"revision":18067,"compact-revision":15489} {"level":"info","ts":"2026-06-15T19:46:50.008697Z","caller":"traceutil/trace.go:171","msg":"trace[22537971] transaction","detail":"{read_only:false; response_revision:22115; number_of_response:1; }","duration":"112.861054ms","start":"2026-06-15T19:46:49.895796Z","end":"2026-06-15T19:46:50.008657Z","steps":["trace[22537971] 'process raft request' (duration: 100.606975ms)","trace[22537971] 'compare' (duration: 12.017933ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:46:50.008754Z","caller":"traceutil/trace.go:171","msg":"trace[620847949] linearizableReadLoop","detail":"{readStateIndex:22857; appliedIndex:22856; }","duration":"111.918208ms","start":"2026-06-15T19:46:49.896815Z","end":"2026-06-15T19:46:50.008733Z","steps":["trace[620847949] 'read index received' (duration: 99.599187ms)","trace[620847949] 'applied index is now lower than readState.Index' (duration: 12.317251ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:46:50.008744Z","caller":"traceutil/trace.go:171","msg":"trace[944941040] transaction","detail":"{read_only:false; response_revision:22116; number_of_response:1; }","duration":"110.446658ms","start":"2026-06-15T19:46:49.898268Z","end":"2026-06-15T19:46:50.008715Z","steps":["trace[944941040] 'process raft request' (duration: 110.370136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:46:50.009181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.204686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-15T19:46:50.009239Z","caller":"traceutil/trace.go:171","msg":"trace[1232750237] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22116; }","duration":"112.443183ms","start":"2026-06-15T19:46:49.896785Z","end":"2026-06-15T19:46:50.009228Z","steps":["trace[1232750237] 'agreement among raft nodes before linearized reading' (duration: 112.010701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:46:50.41086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.571072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:46:50.410967Z","caller":"traceutil/trace.go:171","msg":"trace[776839428] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22117; }","duration":"260.735927ms","start":"2026-06-15T19:46:50.150207Z","end":"2026-06-15T19:46:50.410943Z","steps":["trace[776839428] 'range keys from in-memory index tree' (duration: 260.377157ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:46:50.416943Z","caller":"traceutil/trace.go:171","msg":"trace[518604127] linearizableReadLoop","detail":"{readStateIndex:22860; appliedIndex:22859; }","duration":"100.884052ms","start":"2026-06-15T19:46:50.316027Z","end":"2026-06-15T19:46:50.416911Z","steps":["trace[518604127] 'read index received' (duration: 100.670346ms)","trace[518604127] 'applied index is now lower than readState.Index' (duration: 212.636µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:46:50.416971Z","caller":"traceutil/trace.go:171","msg":"trace[116924386] transaction","detail":"{read_only:false; response_revision:22118; number_of_response:1; }","duration":"127.655203ms","start":"2026-06-15T19:46:50.289292Z","end":"2026-06-15T19:46:50.416947Z","steps":["trace[116924386] 'process raft request' (duration: 127.464618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:46:50.417272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.19073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-15T19:46:50.417387Z","caller":"traceutil/trace.go:171","msg":"trace[1146115282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22118; }","duration":"101.370275ms","start":"2026-06-15T19:46:50.31599Z","end":"2026-06-15T19:46:50.41736Z","steps":["trace[1146115282] 'agreement among raft nodes before linearized reading' (duration: 101.053927ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:49:03.470815Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20315} {"level":"info","ts":"2026-06-15T19:49:03.577133Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20315,"took":"102.462396ms","hash":3409724928,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":42385408,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-06-15T19:49:03.577205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3409724928,"revision":20315,"compact-revision":18067} {"level":"warn","ts":"2026-06-15T19:50:20.455574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.744804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-15T19:50:20.455663Z","caller":"traceutil/trace.go:171","msg":"trace[1213183939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24257; }","duration":"220.847307ms","start":"2026-06-15T19:50:20.234798Z","end":"2026-06-15T19:50:20.455645Z","steps":["trace[1213183939] 'range keys from in-memory index tree' (duration: 220.565569ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:20.587778Z","caller":"traceutil/trace.go:171","msg":"trace[131962996] linearizableReadLoop","detail":"{readStateIndex:25055; appliedIndex:25054; }","duration":"106.041003ms","start":"2026-06-15T19:50:20.481714Z","end":"2026-06-15T19:50:20.587755Z","steps":["trace[131962996] 'read index received' (duration: 105.876998ms)","trace[131962996] 'applied index is now lower than readState.Index' (duration: 163.085µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:20.587895Z","caller":"traceutil/trace.go:171","msg":"trace[1771351597] transaction","detail":"{read_only:false; response_revision:24258; number_of_response:1; }","duration":"128.590276ms","start":"2026-06-15T19:50:20.459294Z","end":"2026-06-15T19:50:20.587884Z","steps":["trace[1771351597] 'process raft request' (duration: 128.307108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:20.588111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.387652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-15T19:50:20.588169Z","caller":"traceutil/trace.go:171","msg":"trace[1884463505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24258; }","duration":"106.488135ms","start":"2026-06-15T19:50:20.481668Z","end":"2026-06-15T19:50:20.588156Z","steps":["trace[1884463505] 'agreement among raft nodes before linearized reading' (duration: 106.32806ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:20.654191Z","caller":"traceutil/trace.go:171","msg":"trace[1705457677] transaction","detail":"{read_only:false; response_revision:24259; number_of_response:1; }","duration":"124.217655ms","start":"2026-06-15T19:50:20.529929Z","end":"2026-06-15T19:50:20.654146Z","steps":["trace[1705457677] 'process raft request' (duration: 123.948058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:20.654227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.350249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-15T19:50:20.654319Z","caller":"traceutil/trace.go:171","msg":"trace[123009612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24259; }","duration":"171.471491ms","start":"2026-06-15T19:50:20.482827Z","end":"2026-06-15T19:50:20.654298Z","steps":["trace[123009612] 'agreement among raft nodes before linearized reading' (duration: 171.221485ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:25.356628Z","caller":"traceutil/trace.go:171","msg":"trace[1594622607] transaction","detail":"{read_only:false; response_revision:24299; number_of_response:1; }","duration":"120.77397ms","start":"2026-06-15T19:50:25.235831Z","end":"2026-06-15T19:50:25.356605Z","steps":["trace[1594622607] 'process raft request' (duration: 120.601335ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:25.437159Z","caller":"traceutil/trace.go:171","msg":"trace[1433377600] transaction","detail":"{read_only:false; response_revision:24300; number_of_response:1; }","duration":"198.279163ms","start":"2026-06-15T19:50:25.238852Z","end":"2026-06-15T19:50:25.437131Z","steps":["trace[1433377600] 'process raft request' (duration: 198.148399ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:25.731645Z","caller":"traceutil/trace.go:171","msg":"trace[491672355] transaction","detail":"{read_only:false; response_revision:24301; number_of_response:1; }","duration":"134.051317ms","start":"2026-06-15T19:50:25.597566Z","end":"2026-06-15T19:50:25.731618Z","steps":["trace[491672355] 'process raft request' (duration: 133.907043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:26.01201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.886317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:50:26.012139Z","caller":"traceutil/trace.go:171","msg":"trace[481369982] transaction","detail":"{read_only:false; response_revision:24302; number_of_response:1; }","duration":"150.0516ms","start":"2026-06-15T19:50:25.862064Z","end":"2026-06-15T19:50:26.012116Z","steps":["trace[481369982] 'process raft request' (duration: 17.952587ms)","trace[481369982] 'compare' (duration: 131.182237ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:26.051078Z","caller":"traceutil/trace.go:171","msg":"trace[998293757] transaction","detail":"{read_only:false; response_revision:24303; number_of_response:1; }","duration":"163.379258ms","start":"2026-06-15T19:50:25.88767Z","end":"2026-06-15T19:50:26.051049Z","steps":["trace[998293757] 'process raft request' (duration: 163.193522ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:26.244821Z","caller":"traceutil/trace.go:171","msg":"trace[100281249] transaction","detail":"{read_only:false; response_revision:24304; number_of_response:1; }","duration":"331.374783ms","start":"2026-06-15T19:50:25.91342Z","end":"2026-06-15T19:50:26.244795Z","steps":["trace[100281249] 'process raft request' (duration: 242.012512ms)","trace[100281249] 'compare' (duration: 88.989791ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:26.244967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:25.913393Z","time spent":"331.503437ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:26.245061Z","caller":"traceutil/trace.go:171","msg":"trace[60078141] transaction","detail":"{read_only:false; response_revision:24306; number_of_response:1; }","duration":"147.115008ms","start":"2026-06-15T19:50:26.097924Z","end":"2026-06-15T19:50:26.245039Z","steps":["trace[60078141] 'process raft request' (duration: 146.782619ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:26.245057Z","caller":"traceutil/trace.go:171","msg":"trace[1153290597] linearizableReadLoop","detail":"{readStateIndex:25103; appliedIndex:25101; }","duration":"184.414899ms","start":"2026-06-15T19:50:26.060601Z","end":"2026-06-15T19:50:26.245016Z","steps":["trace[1153290597] 'read index received' (duration: 94.854903ms)","trace[1153290597] 'applied index is now lower than readState.Index' (duration: 89.555176ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:26.245123Z","caller":"traceutil/trace.go:171","msg":"trace[797067663] transaction","detail":"{read_only:false; response_revision:24305; number_of_response:1; }","duration":"208.094635ms","start":"2026-06-15T19:50:26.036944Z","end":"2026-06-15T19:50:26.245039Z","steps":["trace[797067663] 'process raft request' (duration: 207.696444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:26.245212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.610615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:26.245242Z","caller":"traceutil/trace.go:171","msg":"trace[1284781269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24307; }","duration":"184.672816ms","start":"2026-06-15T19:50:26.060559Z","end":"2026-06-15T19:50:26.245232Z","steps":["trace[1284781269] 'agreement among raft nodes before linearized reading' (duration: 184.624405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:26.24533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.698285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T19:50:26.24539Z","caller":"traceutil/trace.go:171","msg":"trace[607881480] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24307; }","duration":"155.813648ms","start":"2026-06-15T19:50:26.089563Z","end":"2026-06-15T19:50:26.245377Z","steps":["trace[607881480] 'agreement among raft nodes before linearized reading' (duration: 155.663894ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:26.498695Z","caller":"traceutil/trace.go:171","msg":"trace[1240181449] linearizableReadLoop","detail":"{readStateIndex:25106; appliedIndex:25105; }","duration":"146.961955ms","start":"2026-06-15T19:50:26.351698Z","end":"2026-06-15T19:50:26.49866Z","steps":["trace[1240181449] 'read index received' (duration: 98.896795ms)","trace[1240181449] 'applied index is now lower than readState.Index' (duration: 48.06331ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:26.498888Z","caller":"traceutil/trace.go:171","msg":"trace[381210026] transaction","detail":"{read_only:false; response_revision:24308; number_of_response:1; }","duration":"247.748881ms","start":"2026-06-15T19:50:26.251118Z","end":"2026-06-15T19:50:26.498867Z","steps":["trace[381210026] 'process raft request' (duration: 199.506387ms)","trace[381210026] 'compare' (duration: 47.853764ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:26.499376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.654204ms","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-06-15T19:50:26.499457Z","caller":"traceutil/trace.go:171","msg":"trace[1779571945] 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:24308; }","duration":"147.774117ms","start":"2026-06-15T19:50:26.351663Z","end":"2026-06-15T19:50:26.499437Z","steps":["trace[1779571945] 'agreement among raft nodes before linearized reading' (duration: 147.658954ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:26.666178Z","caller":"traceutil/trace.go:171","msg":"trace[1080392401] linearizableReadLoop","detail":"{readStateIndex:25107; appliedIndex:25106; }","duration":"153.861095ms","start":"2026-06-15T19:50:26.512276Z","end":"2026-06-15T19:50:26.666137Z","steps":["trace[1080392401] 'read index received' (duration: 128.674208ms)","trace[1080392401] 'applied index is now lower than readState.Index' (duration: 25.185367ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:26.666368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.06094ms","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-06-15T19:50:26.666444Z","caller":"traceutil/trace.go:171","msg":"trace[25110621] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24308; }","duration":"154.167284ms","start":"2026-06-15T19:50:26.512256Z","end":"2026-06-15T19:50:26.666423Z","steps":["trace[25110621] 'agreement among raft nodes before linearized reading' (duration: 154.012329ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:26.896395Z","caller":"traceutil/trace.go:171","msg":"trace[1437803657] transaction","detail":"{read_only:false; response_revision:24309; number_of_response:1; }","duration":"228.394255ms","start":"2026-06-15T19:50:26.66796Z","end":"2026-06-15T19:50:26.896355Z","steps":["trace[1437803657] 'process raft request' (duration: 212.513116ms)","trace[1437803657] 'compare' (duration: 15.620082ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:26.896579Z","caller":"traceutil/trace.go:171","msg":"trace[708954137] linearizableReadLoop","detail":"{readStateIndex:25109; appliedIndex:25107; }","duration":"223.968102ms","start":"2026-06-15T19:50:26.672591Z","end":"2026-06-15T19:50:26.896559Z","steps":["trace[708954137] 'read index received' (duration: 207.872128ms)","trace[708954137] 'applied index is now lower than readState.Index' (duration: 16.093594ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:26.896612Z","caller":"traceutil/trace.go:171","msg":"trace[1843021749] transaction","detail":"{read_only:false; response_revision:24311; number_of_response:1; }","duration":"222.856751ms","start":"2026-06-15T19:50:26.673722Z","end":"2026-06-15T19:50:26.896579Z","steps":["trace[1843021749] 'process raft request' (duration: 222.618004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:26.896729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.122896ms","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-06-15T19:50:26.896764Z","caller":"traceutil/trace.go:171","msg":"trace[1546170317] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24311; }","duration":"224.201269ms","start":"2026-06-15T19:50:26.672552Z","end":"2026-06-15T19:50:26.896753Z","steps":["trace[1546170317] 'agreement among raft nodes before linearized reading' (duration: 224.122577ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:26.896401Z","caller":"traceutil/trace.go:171","msg":"trace[1698723751] transaction","detail":"{read_only:false; response_revision:24310; number_of_response:1; }","duration":"224.774564ms","start":"2026-06-15T19:50:26.671601Z","end":"2026-06-15T19:50:26.896375Z","steps":["trace[1698723751] 'process raft request' (duration: 224.680662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:26.89681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.84066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-15T19:50:26.896845Z","caller":"traceutil/trace.go:171","msg":"trace[179014206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24311; }","duration":"218.887712ms","start":"2026-06-15T19:50:26.677947Z","end":"2026-06-15T19:50:26.896835Z","steps":["trace[179014206] 'agreement among raft nodes before linearized reading' (duration: 218.738658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:26.897026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.366962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-15T19:50:26.897068Z","caller":"traceutil/trace.go:171","msg":"trace[1959878309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24311; }","duration":"216.432624ms","start":"2026-06-15T19:50:26.680623Z","end":"2026-06-15T19:50:26.897056Z","steps":["trace[1959878309] 'agreement among raft nodes before linearized reading' (duration: 216.245749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:27.108436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.633666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:50:27.108639Z","caller":"traceutil/trace.go:171","msg":"trace[888610931] transaction","detail":"{read_only:false; response_revision:24312; number_of_response:1; }","duration":"200.316089ms","start":"2026-06-15T19:50:26.908291Z","end":"2026-06-15T19:50:27.108607Z","steps":["trace[888610931] 'process raft request' (duration: 96.421116ms)","trace[888610931] 'compare' (duration: 103.486292ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:27.108675Z","caller":"traceutil/trace.go:171","msg":"trace[379072652] transaction","detail":"{read_only:false; response_revision:24313; number_of_response:1; }","duration":"199.842357ms","start":"2026-06-15T19:50:26.908818Z","end":"2026-06-15T19:50:27.10866Z","steps":["trace[379072652] 'process raft request' (duration: 199.783865ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:27.108719Z","caller":"traceutil/trace.go:171","msg":"trace[5016629] linearizableReadLoop","detail":"{readStateIndex:25111; appliedIndex:25110; }","duration":"200.34042ms","start":"2026-06-15T19:50:26.908352Z","end":"2026-06-15T19:50:27.108692Z","steps":["trace[5016629] 'read index received' (duration: 96.373305ms)","trace[5016629] 'applied index is now lower than readState.Index' (duration: 103.963955ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:27.108983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.609838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-15T19:50:27.109008Z","caller":"traceutil/trace.go:171","msg":"trace[881036120] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24313; }","duration":"200.667859ms","start":"2026-06-15T19:50:26.908333Z","end":"2026-06-15T19:50:27.109001Z","steps":["trace[881036120] 'agreement among raft nodes before linearized reading' (duration: 200.439393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:27.394994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.946184ms","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-06-15T19:50:27.395057Z","caller":"traceutil/trace.go:171","msg":"trace[894365743] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24313; }","duration":"185.055597ms","start":"2026-06-15T19:50:27.209987Z","end":"2026-06-15T19:50:27.395042Z","steps":["trace[894365743] 'range keys from in-memory index tree' (duration: 184.7876ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:27.55343Z","caller":"traceutil/trace.go:171","msg":"trace[2005941733] linearizableReadLoop","detail":"{readStateIndex:25113; appliedIndex:25112; }","duration":"112.570753ms","start":"2026-06-15T19:50:27.44084Z","end":"2026-06-15T19:50:27.553411Z","steps":["trace[2005941733] 'read index received' (duration: 112.405598ms)","trace[2005941733] 'applied index is now lower than readState.Index' (duration: 164.325µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:27.553547Z","caller":"traceutil/trace.go:171","msg":"trace[1406784037] transaction","detail":"{read_only:false; response_revision:24314; number_of_response:1; }","duration":"190.115718ms","start":"2026-06-15T19:50:27.363399Z","end":"2026-06-15T19:50:27.553515Z","steps":["trace[1406784037] 'process raft request' (duration: 189.877511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:27.55366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.630084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-06-15T19:50:27.553691Z","caller":"traceutil/trace.go:171","msg":"trace[1197029884] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24314; }","duration":"112.723297ms","start":"2026-06-15T19:50:27.440958Z","end":"2026-06-15T19:50:27.553681Z","steps":["trace[1197029884] 'agreement among raft nodes before linearized reading' (duration: 112.607104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:27.553962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.123538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-15T19:50:27.553986Z","caller":"traceutil/trace.go:171","msg":"trace[1209751960] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24314; }","duration":"113.183589ms","start":"2026-06-15T19:50:27.440794Z","end":"2026-06-15T19:50:27.553978Z","steps":["trace[1209751960] 'agreement among raft nodes before linearized reading' (duration: 112.722737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:27.822758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.401609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:50:27.822917Z","caller":"traceutil/trace.go:171","msg":"trace[452252601] linearizableReadLoop","detail":"{readStateIndex:25114; appliedIndex:25113; }","duration":"263.236149ms","start":"2026-06-15T19:50:27.559659Z","end":"2026-06-15T19:50:27.822896Z","steps":["trace[452252601] 'read index received' (duration: 129.534571ms)","trace[452252601] 'applied index is now lower than readState.Index' (duration: 133.699968ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:27.82351Z","caller":"traceutil/trace.go:171","msg":"trace[1539782665] transaction","detail":"{read_only:false; response_revision:24315; number_of_response:1; }","duration":"263.861137ms","start":"2026-06-15T19:50:27.559606Z","end":"2026-06-15T19:50:27.823468Z","steps":["trace[1539782665] 'process raft request' (duration: 129.643315ms)","trace[1539782665] 'compare' (duration: 132.933556ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:27.823569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.597269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-06-15T19:50:27.823616Z","caller":"traceutil/trace.go:171","msg":"trace[838488439] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:24315; }","duration":"263.665942ms","start":"2026-06-15T19:50:27.559936Z","end":"2026-06-15T19:50:27.823602Z","steps":["trace[838488439] 'agreement among raft nodes before linearized reading' (duration: 263.509407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:27.823796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.112764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-06-15T19:50:27.823848Z","caller":"traceutil/trace.go:171","msg":"trace[838819423] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24315; }","duration":"264.211897ms","start":"2026-06-15T19:50:27.559618Z","end":"2026-06-15T19:50:27.82383Z","steps":["trace[838819423] 'agreement among raft nodes before linearized reading' (duration: 264.086293ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:28.191712Z","caller":"traceutil/trace.go:171","msg":"trace[589405610] transaction","detail":"{read_only:false; response_revision:24317; number_of_response:1; }","duration":"167.79643ms","start":"2026-06-15T19:50:28.023894Z","end":"2026-06-15T19:50:28.191691Z","steps":["trace[589405610] 'process raft request' (duration: 93.977939ms)","trace[589405610] 'compare' (duration: 73.520573ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:28.248978Z","caller":"traceutil/trace.go:171","msg":"trace[1312717069] linearizableReadLoop","detail":"{readStateIndex:25117; appliedIndex:25115; }","duration":"187.256648ms","start":"2026-06-15T19:50:28.061686Z","end":"2026-06-15T19:50:28.248943Z","steps":["trace[1312717069] 'read index received' (duration: 56.163083ms)","trace[1312717069] 'applied index is now lower than readState.Index' (duration: 131.092435ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:28.249138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.436863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:28.249184Z","caller":"traceutil/trace.go:171","msg":"trace[1668675616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24318; }","duration":"187.517276ms","start":"2026-06-15T19:50:28.061654Z","end":"2026-06-15T19:50:28.249171Z","steps":["trace[1668675616] 'agreement among raft nodes before linearized reading' (duration: 187.403302ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:28.249618Z","caller":"traceutil/trace.go:171","msg":"trace[1059765100] transaction","detail":"{read_only:false; response_revision:24318; number_of_response:1; }","duration":"193.43922ms","start":"2026-06-15T19:50:28.056165Z","end":"2026-06-15T19:50:28.249604Z","steps":["trace[1059765100] 'process raft request' (duration: 192.631757ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:28.560313Z","caller":"traceutil/trace.go:171","msg":"trace[617929832] linearizableReadLoop","detail":"{readStateIndex:25118; appliedIndex:25118; }","duration":"309.087427ms","start":"2026-06-15T19:50:28.251204Z","end":"2026-06-15T19:50:28.560291Z","steps":["trace[617929832] 'read index received' (duration: 309.080737ms)","trace[617929832] 'applied index is now lower than readState.Index' (duration: 5.23µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:28.560584Z","caller":"traceutil/trace.go:171","msg":"trace[2049812030] transaction","detail":"{read_only:false; response_revision:24319; number_of_response:1; }","duration":"309.611272ms","start":"2026-06-15T19:50:28.250959Z","end":"2026-06-15T19:50:28.560571Z","steps":["trace[2049812030] 'process raft request' (duration: 309.079247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:28.560751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:28.250939Z","time spent":"309.710064ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:28.70781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.580826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:28.707901Z","caller":"traceutil/trace.go:171","msg":"trace[1148704617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24319; }","duration":"456.70267ms","start":"2026-06-15T19:50:28.251177Z","end":"2026-06-15T19:50:28.707879Z","steps":["trace[1148704617] 'agreement among raft nodes before linearized reading' (duration: 309.359225ms)","trace[1148704617] 'range keys from in-memory index tree' (duration: 147.220531ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:28.707943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:28.251167Z","time spent":"456.767641ms","remote":"127.0.0.1:44710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T19:50:28.709146Z","caller":"traceutil/trace.go:171","msg":"trace[498871206] linearizableReadLoop","detail":"{readStateIndex:25121; appliedIndex:25118; }","duration":"148.665031ms","start":"2026-06-15T19:50:28.560461Z","end":"2026-06-15T19:50:28.709126Z","steps":["trace[498871206] 'read index received' (duration: 60.581355ms)","trace[498871206] 'applied index is now lower than readState.Index' (duration: 88.082866ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:28.709195Z","caller":"traceutil/trace.go:171","msg":"trace[409187219] transaction","detail":"{read_only:false; response_revision:24321; number_of_response:1; }","duration":"457.018938ms","start":"2026-06-15T19:50:28.252155Z","end":"2026-06-15T19:50:28.709173Z","steps":["trace[409187219] 'process raft request' (duration: 456.852124ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:28.709263Z","caller":"traceutil/trace.go:171","msg":"trace[468033197] transaction","detail":"{read_only:false; response_revision:24322; number_of_response:1; }","duration":"311.022611ms","start":"2026-06-15T19:50:28.398231Z","end":"2026-06-15T19:50:28.709254Z","steps":["trace[468033197] 'process raft request' (duration: 310.856846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:28.709354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:28.252146Z","time spent":"457.09187ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:28.709375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:28.398198Z","time spent":"311.094313ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:28.709486Z","caller":"traceutil/trace.go:171","msg":"trace[2028235756] transaction","detail":"{read_only:false; response_revision:24320; number_of_response:1; }","duration":"458.177501ms","start":"2026-06-15T19:50:28.251295Z","end":"2026-06-15T19:50:28.709472Z","steps":["trace[2028235756] 'process raft request' (duration: 369.838588ms)","trace[2028235756] 'compare' (duration: 86.950384ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:28.709575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.336178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-15T19:50:28.709599Z","caller":"traceutil/trace.go:171","msg":"trace[489975385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24322; }","duration":"205.39012ms","start":"2026-06-15T19:50:28.504201Z","end":"2026-06-15T19:50:28.709592Z","steps":["trace[489975385] 'agreement among raft nodes before linearized reading' (duration: 205.272467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:28.709606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:28.251286Z","time spent":"458.264013ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:28.709805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.604626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-15T19:50:28.709831Z","caller":"traceutil/trace.go:171","msg":"trace[1446902420] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24322; }","duration":"239.632446ms","start":"2026-06-15T19:50:28.470192Z","end":"2026-06-15T19:50:28.709824Z","steps":["trace[1446902420] 'agreement among raft nodes before linearized reading' (duration: 239.460862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:28.71002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.025148ms","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-06-15T19:50:28.710046Z","caller":"traceutil/trace.go:171","msg":"trace[656100889] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24322; }","duration":"125.085949ms","start":"2026-06-15T19:50:28.584953Z","end":"2026-06-15T19:50:28.710039Z","steps":["trace[656100889] 'agreement among raft nodes before linearized reading' (duration: 125.012667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:29.422231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738694226574987,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T19:50:29.438574Z","caller":"traceutil/trace.go:171","msg":"trace[1538050835] transaction","detail":"{read_only:false; response_revision:24324; number_of_response:1; }","duration":"525.300946ms","start":"2026-06-15T19:50:28.913249Z","end":"2026-06-15T19:50:29.43855Z","steps":["trace[1538050835] 'process raft request' (duration: 525.119291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:29.438866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:28.913232Z","time spent":"525.402249ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:29.478245Z","caller":"traceutil/trace.go:171","msg":"trace[1280886110] linearizableReadLoop","detail":"{readStateIndex:25125; appliedIndex:25123; }","duration":"556.031006ms","start":"2026-06-15T19:50:28.922195Z","end":"2026-06-15T19:50:29.478226Z","steps":["trace[1280886110] 'read index received' (duration: 516.42099ms)","trace[1280886110] 'applied index is now lower than readState.Index' (duration: 39.609116ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:29.478577Z","caller":"traceutil/trace.go:171","msg":"trace[1165983325] transaction","detail":"{read_only:false; response_revision:24325; number_of_response:1; }","duration":"564.815409ms","start":"2026-06-15T19:50:28.913749Z","end":"2026-06-15T19:50:29.478565Z","steps":["trace[1165983325] 'process raft request' (duration: 561.631351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:29.4787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:28.913741Z","time spent":"564.901071ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:29.47883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.597411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:29.479027Z","caller":"traceutil/trace.go:171","msg":"trace[324580883] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24326; }","duration":"556.829168ms","start":"2026-06-15T19:50:28.922169Z","end":"2026-06-15T19:50:29.478999Z","steps":["trace[324580883] 'agreement among raft nodes before linearized reading' (duration: 556.54424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:29.479066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.932991ms","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-06-15T19:50:29.479104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:28.92216Z","time spent":"556.92391ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-06-15T19:50:29.479134Z","caller":"traceutil/trace.go:171","msg":"trace[1677890818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24326; }","duration":"364.973662ms","start":"2026-06-15T19:50:29.11412Z","end":"2026-06-15T19:50:29.479094Z","steps":["trace[1677890818] 'agreement among raft nodes before linearized reading' (duration: 364.812537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:29.479198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:29.114092Z","time spent":"365.069015ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T19:50:29.479451Z","caller":"traceutil/trace.go:171","msg":"trace[331411539] transaction","detail":"{read_only:false; response_revision:24326; number_of_response:1; }","duration":"457.333286ms","start":"2026-06-15T19:50:29.022097Z","end":"2026-06-15T19:50:29.47943Z","steps":["trace[331411539] 'process raft request' (duration: 456.065631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:29.479628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:29.022078Z","time spent":"457.45149ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:29.479701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.428215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-15T19:50:29.47983Z","caller":"traceutil/trace.go:171","msg":"trace[634746690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24326; }","duration":"365.553729ms","start":"2026-06-15T19:50:29.114226Z","end":"2026-06-15T19:50:29.47978Z","steps":["trace[634746690] 'agreement among raft nodes before linearized reading' (duration: 365.299782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:29.47992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:29.114217Z","time spent":"365.656711ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T19:50:29.715793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.991144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:50:29.715959Z","caller":"traceutil/trace.go:171","msg":"trace[1326386375] transaction","detail":"{read_only:false; response_revision:24328; number_of_response:1; }","duration":"231.619066ms","start":"2026-06-15T19:50:29.484319Z","end":"2026-06-15T19:50:29.715938Z","steps":["trace[1326386375] 'process raft request' (duration: 96.404286ms)","trace[1326386375] 'compare' (duration: 134.879581ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:29.71609Z","caller":"traceutil/trace.go:171","msg":"trace[222324512] linearizableReadLoop","detail":"{readStateIndex:25128; appliedIndex:25126; }","duration":"109.940441ms","start":"2026-06-15T19:50:29.606141Z","end":"2026-06-15T19:50:29.716081Z","steps":["trace[222324512] 'read index received' (duration: 108.023118ms)","trace[222324512] 'applied index is now lower than readState.Index' (duration: 1.916553ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:29.716175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.027093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T19:50:29.716204Z","caller":"traceutil/trace.go:171","msg":"trace[1944291759] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:24329; }","duration":"110.089875ms","start":"2026-06-15T19:50:29.606103Z","end":"2026-06-15T19:50:29.716193Z","steps":["trace[1944291759] 'agreement among raft nodes before linearized reading' (duration: 110.007713ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:29.716358Z","caller":"traceutil/trace.go:171","msg":"trace[1885402131] transaction","detail":"{read_only:false; response_revision:24329; number_of_response:1; }","duration":"156.495668ms","start":"2026-06-15T19:50:29.559832Z","end":"2026-06-15T19:50:29.716328Z","steps":["trace[1885402131] 'process raft request' (duration: 156.054295ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:31.860053Z","caller":"traceutil/trace.go:171","msg":"trace[53023034] transaction","detail":"{read_only:false; response_revision:24342; number_of_response:1; }","duration":"128.900915ms","start":"2026-06-15T19:50:31.731122Z","end":"2026-06-15T19:50:31.860023Z","steps":["trace[53023034] 'process raft request' (duration: 128.72981ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:31.870823Z","caller":"traceutil/trace.go:171","msg":"trace[1020011498] transaction","detail":"{read_only:false; response_revision:24343; number_of_response:1; }","duration":"137.538833ms","start":"2026-06-15T19:50:31.73327Z","end":"2026-06-15T19:50:31.870809Z","steps":["trace[1020011498] 'process raft request' (duration: 137.100811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:40.172699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.20912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:50:40.172812Z","caller":"traceutil/trace.go:171","msg":"trace[1799221371] linearizableReadLoop","detail":"{readStateIndex:25205; appliedIndex:25204; }","duration":"233.865676ms","start":"2026-06-15T19:50:39.938894Z","end":"2026-06-15T19:50:40.17276Z","steps":["trace[1799221371] 'read index received' (duration: 124.524223ms)","trace[1799221371] 'applied index is now lower than readState.Index' (duration: 109.340703ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:40.172842Z","caller":"traceutil/trace.go:171","msg":"trace[333998601] transaction","detail":"{read_only:false; response_revision:24404; number_of_response:1; }","duration":"243.598716ms","start":"2026-06-15T19:50:39.929222Z","end":"2026-06-15T19:50:40.172821Z","steps":["trace[333998601] 'process raft request' (duration: 134.17726ms)","trace[333998601] 'compare' (duration: 109.117788ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:40.172872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.723846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:40.172887Z","caller":"traceutil/trace.go:171","msg":"trace[1070453498] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:24404; }","duration":"240.765478ms","start":"2026-06-15T19:50:39.932116Z","end":"2026-06-15T19:50:40.172882Z","steps":["trace[1070453498] 'agreement among raft nodes before linearized reading' (duration: 240.728847ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:40.358208Z","caller":"traceutil/trace.go:171","msg":"trace[423265388] linearizableReadLoop","detail":"{readStateIndex:25206; appliedIndex:25205; }","duration":"185.356516ms","start":"2026-06-15T19:50:40.172826Z","end":"2026-06-15T19:50:40.358183Z","steps":["trace[423265388] 'read index received' (duration: 101.139647ms)","trace[423265388] 'applied index is now lower than readState.Index' (duration: 84.215309ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:40.358245Z","caller":"traceutil/trace.go:171","msg":"trace[1039840362] transaction","detail":"{read_only:false; response_revision:24405; number_of_response:1; }","duration":"413.791842ms","start":"2026-06-15T19:50:39.944409Z","end":"2026-06-15T19:50:40.358201Z","steps":["trace[1039840362] 'process raft request' (duration: 329.544693ms)","trace[1039840362] 'compare' (duration: 83.990882ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:40.358394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:39.944396Z","time spent":"413.919406ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:40.358395Z","caller":"traceutil/trace.go:171","msg":"trace[1506353657] transaction","detail":"{read_only:false; response_revision:24406; number_of_response:1; }","duration":"126.670863ms","start":"2026-06-15T19:50:40.231685Z","end":"2026-06-15T19:50:40.358356Z","steps":["trace[1506353657] 'process raft request' (duration: 126.440786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:40.358422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.842792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:40.358541Z","caller":"traceutil/trace.go:171","msg":"trace[838251899] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:24406; }","duration":"291.014677ms","start":"2026-06-15T19:50:40.067513Z","end":"2026-06-15T19:50:40.358527Z","steps":["trace[838251899] 'agreement among raft nodes before linearized reading' (duration: 290.813522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:40.358601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.404367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-15T19:50:40.358663Z","caller":"traceutil/trace.go:171","msg":"trace[753918167] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24406; }","duration":"223.508521ms","start":"2026-06-15T19:50:40.135137Z","end":"2026-06-15T19:50:40.358646Z","steps":["trace[753918167] 'agreement among raft nodes before linearized reading' (duration: 223.10939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:40.36063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.462334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:40.360673Z","caller":"traceutil/trace.go:171","msg":"trace[757192288] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:24407; }","duration":"157.513845ms","start":"2026-06-15T19:50:40.203148Z","end":"2026-06-15T19:50:40.360662Z","steps":["trace[757192288] 'agreement among raft nodes before linearized reading' (duration: 157.435253ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:43.644202Z","caller":"traceutil/trace.go:171","msg":"trace[210173623] transaction","detail":"{read_only:false; response_revision:24425; number_of_response:1; }","duration":"126.959251ms","start":"2026-06-15T19:50:43.517219Z","end":"2026-06-15T19:50:43.644178Z","steps":["trace[210173623] 'process raft request' (duration: 126.794426ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:43.948851Z","caller":"traceutil/trace.go:171","msg":"trace[1481365706] transaction","detail":"{read_only:false; response_revision:24427; number_of_response:1; }","duration":"208.104484ms","start":"2026-06-15T19:50:43.740377Z","end":"2026-06-15T19:50:43.948481Z","steps":["trace[1481365706] 'process raft request' (duration: 207.999281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:43.94893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.573064ms","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-06-15T19:50:43.948951Z","caller":"traceutil/trace.go:171","msg":"trace[667270603] linearizableReadLoop","detail":"{readStateIndex:25228; appliedIndex:25226; }","duration":"418.455532ms","start":"2026-06-15T19:50:43.530324Z","end":"2026-06-15T19:50:43.948779Z","steps":["trace[667270603] 'read index received' (duration: 113.667403ms)","trace[667270603] 'applied index is now lower than readState.Index' (duration: 304.785559ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:43.949032Z","caller":"traceutil/trace.go:171","msg":"trace[1182291634] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24428; }","duration":"418.76813ms","start":"2026-06-15T19:50:43.530236Z","end":"2026-06-15T19:50:43.949004Z","steps":["trace[1182291634] 'agreement among raft nodes before linearized reading' (duration: 418.549324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:43.949092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:43.530214Z","time spent":"418.865393ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-15T19:50:43.949087Z","caller":"traceutil/trace.go:171","msg":"trace[910392864] transaction","detail":"{read_only:false; response_revision:24426; number_of_response:1; }","duration":"427.160372ms","start":"2026-06-15T19:50:43.521621Z","end":"2026-06-15T19:50:43.948781Z","steps":["trace[910392864] 'process raft request' (duration: 374.516186ms)","trace[910392864] 'compare' (duration: 52.109251ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:43.949168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.79921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:43.949263Z","caller":"traceutil/trace.go:171","msg":"trace[558307032] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24428; }","duration":"295.911733ms","start":"2026-06-15T19:50:43.653315Z","end":"2026-06-15T19:50:43.949226Z","steps":["trace[558307032] 'agreement among raft nodes before linearized reading' (duration: 295.79651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:43.949273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.153695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-15T19:50:43.949319Z","caller":"traceutil/trace.go:171","msg":"trace[136232266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24428; }","duration":"362.243997ms","start":"2026-06-15T19:50:43.587063Z","end":"2026-06-15T19:50:43.949307Z","steps":["trace[136232266] 'agreement among raft nodes before linearized reading' (duration: 362.037902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:43.949309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:43.521611Z","time spent":"427.580874ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:43.949366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:43.587044Z","time spent":"362.310339ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T19:50:44.238923Z","caller":"traceutil/trace.go:171","msg":"trace[483752459] transaction","detail":"{read_only:false; response_revision:24431; number_of_response:1; }","duration":"257.998945ms","start":"2026-06-15T19:50:43.980905Z","end":"2026-06-15T19:50:44.238904Z","steps":["trace[483752459] 'process raft request' (duration: 257.345977ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:44.534347Z","caller":"traceutil/trace.go:171","msg":"trace[230965996] transaction","detail":"{read_only:false; response_revision:24433; number_of_response:1; }","duration":"272.523096ms","start":"2026-06-15T19:50:44.261801Z","end":"2026-06-15T19:50:44.534324Z","steps":["trace[230965996] 'process raft request' (duration: 272.356921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.069377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.677677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:50:45.069726Z","caller":"traceutil/trace.go:171","msg":"trace[1112501115] linearizableReadLoop","detail":"{readStateIndex:25237; appliedIndex:25234; }","duration":"698.622048ms","start":"2026-06-15T19:50:44.371083Z","end":"2026-06-15T19:50:45.069705Z","steps":["trace[1112501115] 'read index received' (duration: 163.123071ms)","trace[1112501115] 'applied index is now lower than readState.Index' (duration: 535.497607ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:45.06981Z","caller":"traceutil/trace.go:171","msg":"trace[188882727] transaction","detail":"{read_only:false; response_revision:24434; number_of_response:1; }","duration":"700.316235ms","start":"2026-06-15T19:50:44.369455Z","end":"2026-06-15T19:50:45.069771Z","steps":["trace[188882727] 'process raft request' (duration: 511.114953ms)","trace[188882727] 'compare' (duration: 188.243506ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:45.069859Z","caller":"traceutil/trace.go:171","msg":"trace[1830707705] transaction","detail":"{read_only:false; response_revision:24435; number_of_response:1; }","duration":"699.287467ms","start":"2026-06-15T19:50:44.370516Z","end":"2026-06-15T19:50:45.069804Z","steps":["trace[1830707705] 'process raft request' (duration: 698.995779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.069982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.875095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-15T19:50:45.07001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.36941Z","time spent":"700.4877ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:45.070063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.370503Z","time spent":"699.459151ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:45.070078Z","caller":"traceutil/trace.go:171","msg":"trace[254889081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24435; }","duration":"699.012019ms","start":"2026-06-15T19:50:44.371045Z","end":"2026-06-15T19:50:45.070057Z","steps":["trace[254889081] 'agreement among raft nodes before linearized reading' (duration: 698.740601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.070127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.371025Z","time spent":"699.093382ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T19:50:45.668344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.161028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:50:45.668688Z","caller":"traceutil/trace.go:171","msg":"trace[227031247] transaction","detail":"{read_only:false; response_revision:24439; number_of_response:1; }","duration":"901.819828ms","start":"2026-06-15T19:50:44.766847Z","end":"2026-06-15T19:50:45.668667Z","steps":["trace[227031247] 'process raft request' (duration: 901.737096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.668764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.766829Z","time spent":"901.89782ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:45.668888Z","caller":"traceutil/trace.go:171","msg":"trace[35745273] transaction","detail":"{read_only:false; response_revision:24438; number_of_response:1; }","duration":"903.862994ms","start":"2026-06-15T19:50:44.764985Z","end":"2026-06-15T19:50:45.668848Z","steps":["trace[35745273] 'process raft request' (duration: 903.556026ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:45.668899Z","caller":"traceutil/trace.go:171","msg":"trace[1697682026] transaction","detail":"{read_only:false; response_revision:24437; number_of_response:1; }","duration":"1.037637133s","start":"2026-06-15T19:50:44.631213Z","end":"2026-06-15T19:50:45.668851Z","steps":["trace[1697682026] 'process raft request' (duration: 1.037226172s)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:45.668896Z","caller":"traceutil/trace.go:171","msg":"trace[736211579] transaction","detail":"{read_only:false; response_revision:24436; number_of_response:1; }","duration":"1.127628941s","start":"2026-06-15T19:50:44.541227Z","end":"2026-06-15T19:50:45.668856Z","steps":["trace[736211579] 'process raft request' (duration: 821.856076ms)","trace[736211579] 'compare' (duration: 305.034035ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:45.668983Z","caller":"traceutil/trace.go:171","msg":"trace[472196266] transaction","detail":"{read_only:false; response_revision:24440; number_of_response:1; }","duration":"687.846981ms","start":"2026-06-15T19:50:44.981115Z","end":"2026-06-15T19:50:45.668962Z","steps":["trace[472196266] 'process raft request' (duration: 687.517412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.669047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.764954Z","time spent":"904.003948ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:45.66906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.981088Z","time spent":"687.933143ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:45.66906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.631182Z","time spent":"1.037793067s","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:45.669051Z","caller":"traceutil/trace.go:171","msg":"trace[345515665] transaction","detail":"{read_only:false; response_revision:24441; number_of_response:1; }","duration":"594.578072ms","start":"2026-06-15T19:50:45.074453Z","end":"2026-06-15T19:50:45.669031Z","steps":["trace[345515665] 'process raft request' (duration: 594.441909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.669092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.716292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-15T19:50:45.669111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.541201Z","time spent":"1.127805357s","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2720,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:45.669162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.074431Z","time spent":"594.668434ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:45.669175Z","caller":"traceutil/trace.go:171","msg":"trace[911685889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24441; }","duration":"608.858397ms","start":"2026-06-15T19:50:45.060299Z","end":"2026-06-15T19:50:45.669158Z","steps":["trace[911685889] 'agreement among raft nodes before linearized reading' (duration: 608.705943ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:45.66901Z","caller":"traceutil/trace.go:171","msg":"trace[108139872] linearizableReadLoop","detail":"{readStateIndex:25242; appliedIndex:25237; }","duration":"599.23348ms","start":"2026-06-15T19:50:45.069765Z","end":"2026-06-15T19:50:45.668998Z","steps":["trace[108139872] 'read index received' (duration: 293.334221ms)","trace[108139872] 'applied index is now lower than readState.Index' (duration: 305.898379ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:45.66913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.923416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-15T19:50:45.669222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.060273Z","time spent":"608.941889ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T19:50:45.669207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.729767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-15T19:50:45.669253Z","caller":"traceutil/trace.go:171","msg":"trace[120034941] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:24441; }","duration":"998.048289ms","start":"2026-06-15T19:50:44.671194Z","end":"2026-06-15T19:50:45.669242Z","steps":["trace[120034941] 'agreement among raft nodes before linearized reading' (duration: 997.895535ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:45.669287Z","caller":"traceutil/trace.go:171","msg":"trace[136587013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24441; }","duration":"845.85007ms","start":"2026-06-15T19:50:44.823417Z","end":"2026-06-15T19:50:45.669267Z","steps":["trace[136587013] 'agreement among raft nodes before linearized reading' (duration: 845.591283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.669294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.671125Z","time spent":"998.151352ms","remote":"127.0.0.1:55180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:50:45.669329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.823399Z","time spent":"845.920622ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-15T19:50:45.669419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.152539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:45.669446Z","caller":"traceutil/trace.go:171","msg":"trace[1514611683] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:24441; }","duration":"147.217701ms","start":"2026-06-15T19:50:45.522217Z","end":"2026-06-15T19:50:45.669435Z","steps":["trace[1514611683] 'agreement among raft nodes before linearized reading' (duration: 147.16539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.669512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.439985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:45.669602Z","caller":"traceutil/trace.go:171","msg":"trace[485733202] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24441; }","duration":"915.611799ms","start":"2026-06-15T19:50:44.753965Z","end":"2026-06-15T19:50:45.669577Z","steps":["trace[485733202] 'agreement among raft nodes before linearized reading' (duration: 915.105805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.669651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:44.753942Z","time spent":"915.697502ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:50:45.670091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.534966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-15T19:50:45.670133Z","caller":"traceutil/trace.go:171","msg":"trace[1923111166] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24441; }","duration":"221.604467ms","start":"2026-06-15T19:50:45.448517Z","end":"2026-06-15T19:50:45.670121Z","steps":["trace[1923111166] 'agreement among raft nodes before linearized reading' (duration: 221.478934ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:45.926347Z","caller":"traceutil/trace.go:171","msg":"trace[1914624782] linearizableReadLoop","detail":"{readStateIndex:25244; appliedIndex:25243; }","duration":"252.296497ms","start":"2026-06-15T19:50:45.674021Z","end":"2026-06-15T19:50:45.926317Z","steps":["trace[1914624782] 'read index received' (duration: 252.101132ms)","trace[1914624782] 'applied index is now lower than readState.Index' (duration: 194.205µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:45.926447Z","caller":"traceutil/trace.go:171","msg":"trace[2041021828] transaction","detail":"{read_only:false; response_revision:24442; number_of_response:1; }","duration":"252.446081ms","start":"2026-06-15T19:50:45.673958Z","end":"2026-06-15T19:50:45.926404Z","steps":["trace[2041021828] 'process raft request' (duration: 252.119042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:45.926568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.520373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-15T19:50:45.926618Z","caller":"traceutil/trace.go:171","msg":"trace[1780238902] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24442; }","duration":"252.613796ms","start":"2026-06-15T19:50:45.673989Z","end":"2026-06-15T19:50:45.926603Z","steps":["trace[1780238902] 'agreement among raft nodes before linearized reading' (duration: 252.424831ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:45.927519Z","caller":"traceutil/trace.go:171","msg":"trace[961151851] transaction","detail":"{read_only:false; response_revision:24443; number_of_response:1; }","duration":"253.297265ms","start":"2026-06-15T19:50:45.674203Z","end":"2026-06-15T19:50:45.9275Z","steps":["trace[961151851] 'process raft request' (duration: 253.038398ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:45.927653Z","caller":"traceutil/trace.go:171","msg":"trace[796497929] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"250.112287ms","start":"2026-06-15T19:50:45.677521Z","end":"2026-06-15T19:50:45.927633Z","steps":["trace[796497929] 'process raft request' (duration: 249.896551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.331452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.739433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:50:46.331555Z","caller":"traceutil/trace.go:171","msg":"trace[1169198077] transaction","detail":"{read_only:false; response_revision:24445; number_of_response:1; }","duration":"398.85747ms","start":"2026-06-15T19:50:45.932677Z","end":"2026-06-15T19:50:46.331534Z","steps":["trace[1169198077] 'process raft request' (duration: 100.963782ms)","trace[1169198077] 'compare' (duration: 297.545268ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:46.331607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.932657Z","time spent":"398.929091ms","remote":"127.0.0.1:44888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T19:50:46.348917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.933164Z","time spent":"415.744596ms","remote":"127.0.0.1:44792","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-15T19:50:46.349127Z","caller":"traceutil/trace.go:171","msg":"trace[1003429189] transaction","detail":"{read_only:false; response_revision:24446; number_of_response:1; }","duration":"415.738057ms","start":"2026-06-15T19:50:45.933362Z","end":"2026-06-15T19:50:46.3491Z","steps":["trace[1003429189] 'process raft request' (duration: 415.444249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.349244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.933339Z","time spent":"415.84949ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1237,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T19:50:46.349259Z","caller":"traceutil/trace.go:171","msg":"trace[334782091] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"414.088891ms","start":"2026-06-15T19:50:45.935127Z","end":"2026-06-15T19:50:46.349216Z","steps":["trace[334782091] 'process raft request' (duration: 413.853424ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:46.349301Z","caller":"traceutil/trace.go:171","msg":"trace[1558729065] transaction","detail":"{read_only:false; response_revision:24448; number_of_response:1; }","duration":"395.710752ms","start":"2026-06-15T19:50:45.953557Z","end":"2026-06-15T19:50:46.349268Z","steps":["trace[1558729065] 'process raft request' (duration: 395.581269ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:46.349444Z","caller":"traceutil/trace.go:171","msg":"trace[662945301] linearizableReadLoop","detail":"{readStateIndex:25252; appliedIndex:25246; }","duration":"379.72842ms","start":"2026-06-15T19:50:45.969689Z","end":"2026-06-15T19:50:46.349418Z","steps":["trace[662945301] 'read index received' (duration: 64.02049ms)","trace[662945301] 'applied index is now lower than readState.Index' (duration: 315.70595ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:46.349509Z","caller":"traceutil/trace.go:171","msg":"trace[346806077] transaction","detail":"{read_only:false; response_revision:24449; number_of_response:1; }","duration":"388.967646ms","start":"2026-06-15T19:50:45.960522Z","end":"2026-06-15T19:50:46.34949Z","steps":["trace[346806077] 'process raft request' (duration: 388.684139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.349524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.953547Z","time spent":"395.858427ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:46.349621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.480313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-15T19:50:46.349631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.960495Z","time spent":"389.049619ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:46.349655Z","caller":"traceutil/trace.go:171","msg":"trace[922008196] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24449; }","duration":"320.524944ms","start":"2026-06-15T19:50:46.029122Z","end":"2026-06-15T19:50:46.349647Z","steps":["trace[922008196] 'agreement among raft nodes before linearized reading' (duration: 320.458762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.349664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.858963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-15T19:50:46.349704Z","caller":"traceutil/trace.go:171","msg":"trace[1868352612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24449; }","duration":"367.947225ms","start":"2026-06-15T19:50:45.981745Z","end":"2026-06-15T19:50:46.349692Z","steps":["trace[1868352612] 'agreement among raft nodes before linearized reading' (duration: 367.812721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.349745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.981728Z","time spent":"368.008476ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T19:50:46.349676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:46.029091Z","time spent":"320.580735ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:50:46.3497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.005519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-15T19:50:46.349785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.480153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:46.349805Z","caller":"traceutil/trace.go:171","msg":"trace[6452855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24449; }","duration":"380.144122ms","start":"2026-06-15T19:50:45.969655Z","end":"2026-06-15T19:50:46.349799Z","steps":["trace[6452855] 'agreement among raft nodes before linearized reading' (duration: 379.874725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.349822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.969644Z","time spent":"380.172994ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-15T19:50:46.349832Z","caller":"traceutil/trace.go:171","msg":"trace[1943109826] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24449; }","duration":"362.568736ms","start":"2026-06-15T19:50:45.987245Z","end":"2026-06-15T19:50:46.349814Z","steps":["trace[1943109826] 'agreement among raft nodes before linearized reading' (duration: 362.500595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.349872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.98722Z","time spent":"362.644108ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-15T19:50:46.350082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:45.93512Z","time spent":"414.238275ms","remote":"127.0.0.1:44780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":123896,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:46.546996Z","caller":"traceutil/trace.go:171","msg":"trace[1782313863] transaction","detail":"{read_only:false; response_revision:24450; number_of_response:1; }","duration":"195.713062ms","start":"2026-06-15T19:50:46.351254Z","end":"2026-06-15T19:50:46.546967Z","steps":["trace[1782313863] 'process raft request' (duration: 195.498566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.802212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.921813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:50:46.802563Z","caller":"traceutil/trace.go:171","msg":"trace[933702473] linearizableReadLoop","detail":"{readStateIndex:25254; appliedIndex:25253; }","duration":"449.47611ms","start":"2026-06-15T19:50:46.353068Z","end":"2026-06-15T19:50:46.802544Z","steps":["trace[933702473] 'read index received' (duration: 193.862281ms)","trace[933702473] 'applied index is now lower than readState.Index' (duration: 255.612809ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:46.802667Z","caller":"traceutil/trace.go:171","msg":"trace[998958486] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"446.346723ms","start":"2026-06-15T19:50:46.35631Z","end":"2026-06-15T19:50:46.802657Z","steps":["trace[998958486] 'process raft request' (duration: 446.121066ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:46.802666Z","caller":"traceutil/trace.go:171","msg":"trace[1324077087] transaction","detail":"{read_only:false; response_revision:24452; number_of_response:1; }","duration":"446.502607ms","start":"2026-06-15T19:50:46.356128Z","end":"2026-06-15T19:50:46.802631Z","steps":["trace[1324077087] 'process raft request' (duration: 446.203309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.802741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:46.356304Z","time spent":"446.388964ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:46.802854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.774188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-15T19:50:46.80286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:46.356116Z","time spent":"446.62864ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:46.802905Z","caller":"traceutil/trace.go:171","msg":"trace[1423183695] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24455; }","duration":"449.829639ms","start":"2026-06-15T19:50:46.353062Z","end":"2026-06-15T19:50:46.802892Z","steps":["trace[1423183695] 'agreement among raft nodes before linearized reading' (duration: 449.708266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.80294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:46.353008Z","time spent":"449.925221ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-15T19:50:46.803158Z","caller":"traceutil/trace.go:171","msg":"trace[1903059363] transaction","detail":"{read_only:false; response_revision:24454; number_of_response:1; }","duration":"446.349993ms","start":"2026-06-15T19:50:46.356794Z","end":"2026-06-15T19:50:46.803144Z","steps":["trace[1903059363] 'process raft request' (duration: 445.670274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.803224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:46.356786Z","time spent":"446.402044ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:46.803237Z","caller":"traceutil/trace.go:171","msg":"trace[856605959] transaction","detail":"{read_only:false; response_revision:24455; number_of_response:1; }","duration":"370.319441ms","start":"2026-06-15T19:50:46.432886Z","end":"2026-06-15T19:50:46.803205Z","steps":["trace[856605959] 'process raft request' (duration: 369.612201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.803275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.099523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:46.803304Z","caller":"traceutil/trace.go:171","msg":"trace[264091495] transaction","detail":"{read_only:false; response_revision:24451; number_of_response:1; }","duration":"451.822374ms","start":"2026-06-15T19:50:46.351459Z","end":"2026-06-15T19:50:46.803282Z","steps":["trace[264091495] 'process raft request' (duration: 316.758109ms)","trace[264091495] 'compare' (duration: 133.644956ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:46.803335Z","caller":"traceutil/trace.go:171","msg":"trace[1036846305] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24455; }","duration":"409.216526ms","start":"2026-06-15T19:50:46.394103Z","end":"2026-06-15T19:50:46.803319Z","steps":["trace[1036846305] 'agreement among raft nodes before linearized reading' (duration: 409.096273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.803379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:46.394082Z","time spent":"409.283607ms","remote":"127.0.0.1:45114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:50:46.803388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:46.35143Z","time spent":"451.917667ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T19:50:46.803415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:46.432861Z","time spent":"370.444724ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:50:46.80364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.184208ms","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-06-15T19:50:46.803698Z","caller":"traceutil/trace.go:171","msg":"trace[14547676] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:24455; }","duration":"122.296152ms","start":"2026-06-15T19:50:46.681389Z","end":"2026-06-15T19:50:46.803685Z","steps":["trace[14547676] 'agreement among raft nodes before linearized reading' (duration: 122.20037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:46.803764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.848358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.20\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-15T19:50:46.803821Z","caller":"traceutil/trace.go:171","msg":"trace[58738787] range","detail":"{range_begin:/registry/masterleases/199.19.213.20; range_end:; response_count:1; response_revision:24455; }","duration":"292.950311ms","start":"2026-06-15T19:50:46.510861Z","end":"2026-06-15T19:50:46.803811Z","steps":["trace[58738787] 'agreement among raft nodes before linearized reading' (duration: 292.762345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:48.33748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.866887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d2a30823-0f4e-4e84-b36b-0d709a8bf81a\" ","response":"range_response_count:1 size:3643"} {"level":"warn","ts":"2026-06-15T19:50:48.337528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.549721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-15T19:50:48.337538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.439463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:50:48.337563Z","caller":"traceutil/trace.go:171","msg":"trace[1528165308] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d2a30823-0f4e-4e84-b36b-0d709a8bf81a; range_end:; response_count:1; response_revision:24515; }","duration":"101.99646ms","start":"2026-06-15T19:50:48.235544Z","end":"2026-06-15T19:50:48.33754Z","steps":["trace[1528165308] 'range keys from in-memory index tree' (duration: 101.699422ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:48.337599Z","caller":"traceutil/trace.go:171","msg":"trace[579328928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24515; }","duration":"278.657044ms","start":"2026-06-15T19:50:48.058923Z","end":"2026-06-15T19:50:48.33758Z","steps":["trace[579328928] 'range keys from in-memory index tree' (duration: 278.4787ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:48.337602Z","caller":"traceutil/trace.go:171","msg":"trace[2031771562] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:24515; }","duration":"225.557247ms","start":"2026-06-15T19:50:48.112029Z","end":"2026-06-15T19:50:48.337586Z","steps":["trace[2031771562] 'count revisions from in-memory index tree' (duration: 225.33659ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:50.575336Z","caller":"traceutil/trace.go:171","msg":"trace[29827679] transaction","detail":"{read_only:false; response_revision:24533; number_of_response:1; }","duration":"208.418093ms","start":"2026-06-15T19:50:50.366894Z","end":"2026-06-15T19:50:50.575312Z","steps":["trace[29827679] 'process raft request' (duration: 208.252178ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:50.614222Z","caller":"traceutil/trace.go:171","msg":"trace[539141205] linearizableReadLoop","detail":"{readStateIndex:25338; appliedIndex:25336; }","duration":"205.510493ms","start":"2026-06-15T19:50:50.408687Z","end":"2026-06-15T19:50:50.614197Z","steps":["trace[539141205] 'read index received' (duration: 166.434733ms)","trace[539141205] 'applied index is now lower than readState.Index' (duration: 39.07466ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:50.614282Z","caller":"traceutil/trace.go:171","msg":"trace[1868368727] transaction","detail":"{read_only:false; response_revision:24534; number_of_response:1; }","duration":"246.577498ms","start":"2026-06-15T19:50:50.367676Z","end":"2026-06-15T19:50:50.614253Z","steps":["trace[1868368727] 'process raft request' (duration: 246.378392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:50.61435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.642407ms","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-06-15T19:50:50.6144Z","caller":"traceutil/trace.go:171","msg":"trace[916365229] 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:24534; }","duration":"205.75521ms","start":"2026-06-15T19:50:50.408633Z","end":"2026-06-15T19:50:50.614388Z","steps":["trace[916365229] 'agreement among raft nodes before linearized reading' (duration: 205.655687ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:54.680741Z","caller":"traceutil/trace.go:171","msg":"trace[713336218] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"300.065748ms","start":"2026-06-15T19:50:54.380642Z","end":"2026-06-15T19:50:54.680708Z","steps":["trace[713336218] 'process raft request' (duration: 299.880723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:54.680934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:50:54.380624Z","time spent":"300.212932ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10633,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:50:54.681771Z","caller":"traceutil/trace.go:171","msg":"trace[2094909373] linearizableReadLoop","detail":"{readStateIndex:25386; appliedIndex:25386; }","duration":"226.146044ms","start":"2026-06-15T19:50:54.455608Z","end":"2026-06-15T19:50:54.681754Z","steps":["trace[2094909373] 'read index received' (duration: 226.139334ms)","trace[2094909373] 'applied index is now lower than readState.Index' (duration: 5.53µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:50:54.681945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.339659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-15T19:50:54.681997Z","caller":"traceutil/trace.go:171","msg":"trace[1578324310] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24580; }","duration":"226.432362ms","start":"2026-06-15T19:50:54.455549Z","end":"2026-06-15T19:50:54.681981Z","steps":["trace[1578324310] 'agreement among raft nodes before linearized reading' (duration: 226.273658ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:54.884105Z","caller":"traceutil/trace.go:171","msg":"trace[76994086] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"291.867152ms","start":"2026-06-15T19:50:54.592211Z","end":"2026-06-15T19:50:54.884078Z","steps":["trace[76994086] 'process raft request' (duration: 291.649666ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:54.884251Z","caller":"traceutil/trace.go:171","msg":"trace[1114932441] linearizableReadLoop","detail":"{readStateIndex:25388; appliedIndex:25386; }","duration":"196.842733ms","start":"2026-06-15T19:50:54.687384Z","end":"2026-06-15T19:50:54.884227Z","steps":["trace[1114932441] 'read index received' (duration: 196.571895ms)","trace[1114932441] 'applied index is now lower than readState.Index' (duration: 269.068µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:50:54.884313Z","caller":"traceutil/trace.go:171","msg":"trace[565358183] transaction","detail":"{read_only:false; response_revision:24582; number_of_response:1; }","duration":"258.666434ms","start":"2026-06-15T19:50:54.625622Z","end":"2026-06-15T19:50:54.884288Z","steps":["trace[565358183] 'process raft request' (duration: 258.410126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:50:54.884554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.132432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d2a30823-0f4e-4e84-b36b-0d709a8bf81a\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-15T19:50:54.884612Z","caller":"traceutil/trace.go:171","msg":"trace[1819975868] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d2a30823-0f4e-4e84-b36b-0d709a8bf81a; range_end:; response_count:1; response_revision:24582; }","duration":"197.254275ms","start":"2026-06-15T19:50:54.687337Z","end":"2026-06-15T19:50:54.884591Z","steps":["trace[1819975868] 'agreement among raft nodes before linearized reading' (duration: 196.982997ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:54.930188Z","caller":"traceutil/trace.go:171","msg":"trace[1134709943] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"233.360963ms","start":"2026-06-15T19:50:54.696797Z","end":"2026-06-15T19:50:54.930157Z","steps":["trace[1134709943] 'process raft request' (duration: 233.082395ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:54.930489Z","caller":"traceutil/trace.go:171","msg":"trace[104553369] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"233.60908ms","start":"2026-06-15T19:50:54.696847Z","end":"2026-06-15T19:50:54.930457Z","steps":["trace[104553369] 'process raft request' (duration: 233.214919ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:54.930618Z","caller":"traceutil/trace.go:171","msg":"trace[660889500] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"233.6327ms","start":"2026-06-15T19:50:54.696932Z","end":"2026-06-15T19:50:54.930564Z","steps":["trace[660889500] 'process raft request' (duration: 233.474806ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:50:54.930677Z","caller":"traceutil/trace.go:171","msg":"trace[1406892505] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"233.749243ms","start":"2026-06-15T19:50:54.696896Z","end":"2026-06-15T19:50:54.930645Z","steps":["trace[1406892505] 'process raft request' (duration: 233.422424ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:22.345461Z","caller":"traceutil/trace.go:171","msg":"trace[496401323] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"234.280449ms","start":"2026-06-15T19:51:22.11114Z","end":"2026-06-15T19:51:22.34542Z","steps":["trace[496401323] 'process raft request' (duration: 234.157905ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:22.406835Z","caller":"traceutil/trace.go:171","msg":"trace[198370495] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"295.618185ms","start":"2026-06-15T19:51:22.11119Z","end":"2026-06-15T19:51:22.406808Z","steps":["trace[198370495] 'process raft request' (duration: 267.109987ms)","trace[198370495] 'compare' (duration: 28.163528ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:51:22.681264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.685095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:51:22.681355Z","caller":"traceutil/trace.go:171","msg":"trace[1561416289] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24811; }","duration":"155.809119ms","start":"2026-06-15T19:51:22.525521Z","end":"2026-06-15T19:51:22.68133Z","steps":["trace[1561416289] 'range keys from in-memory index tree' (duration: 155.620433ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:23.099055Z","caller":"traceutil/trace.go:171","msg":"trace[1886420971] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"112.321807ms","start":"2026-06-15T19:51:22.986715Z","end":"2026-06-15T19:51:23.099037Z","steps":["trace[1886420971] 'process raft request' (duration: 112.195683ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:23.409659Z","caller":"traceutil/trace.go:171","msg":"trace[897533350] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"207.253372ms","start":"2026-06-15T19:51:23.202386Z","end":"2026-06-15T19:51:23.40964Z","steps":["trace[897533350] 'process raft request' (duration: 207.225381ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:23.409812Z","caller":"traceutil/trace.go:171","msg":"trace[177700088] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"208.825345ms","start":"2026-06-15T19:51:23.200963Z","end":"2026-06-15T19:51:23.409789Z","steps":["trace[177700088] 'process raft request' (duration: 208.621169ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:23.409843Z","caller":"traceutil/trace.go:171","msg":"trace[710446593] linearizableReadLoop","detail":"{readStateIndex:25626; appliedIndex:25623; }","duration":"372.641274ms","start":"2026-06-15T19:51:23.037165Z","end":"2026-06-15T19:51:23.409806Z","steps":["trace[710446593] 'read index received' (duration: 61.774978ms)","trace[710446593] 'applied index is now lower than readState.Index' (duration: 310.859886ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:23.409848Z","caller":"traceutil/trace.go:171","msg":"trace[699669576] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"409.111114ms","start":"2026-06-15T19:51:23.000671Z","end":"2026-06-15T19:51:23.409782Z","steps":["trace[699669576] 'process raft request' (duration: 408.824986ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:23.409851Z","caller":"traceutil/trace.go:171","msg":"trace[248941838] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"213.508304ms","start":"2026-06-15T19:51:23.196291Z","end":"2026-06-15T19:51:23.409799Z","steps":["trace[248941838] 'process raft request' (duration: 213.267988ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:23.409901Z","caller":"traceutil/trace.go:171","msg":"trace[1313790603] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"410.131641ms","start":"2026-06-15T19:51:22.999668Z","end":"2026-06-15T19:51:23.4098Z","steps":["trace[1313790603] 'process raft request' (duration: 389.504271ms)","trace[1313790603] 'compare' (duration: 20.139047ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:51:23.410031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.000663Z","time spent":"409.275628ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:51:23.410059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:22.999651Z","time spent":"410.311677ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:51:23.410076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.904232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418459"} {"level":"warn","ts":"2026-06-15T19:51:23.410077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.954634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-15T19:51:23.410115Z","caller":"traceutil/trace.go:171","msg":"trace[251217285] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24817; }","duration":"372.979615ms","start":"2026-06-15T19:51:23.037124Z","end":"2026-06-15T19:51:23.410104Z","steps":["trace[251217285] 'agreement among raft nodes before linearized reading' (duration: 372.788459ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:23.41012Z","caller":"traceutil/trace.go:171","msg":"trace[1537133954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24817; }","duration":"339.035796ms","start":"2026-06-15T19:51:23.071074Z","end":"2026-06-15T19:51:23.410109Z","steps":["trace[1537133954] 'agreement among raft nodes before linearized reading' (duration: 338.921073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:23.410144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.037108Z","time spent":"373.030446ms","remote":"127.0.0.1:40170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418482,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-06-15T19:51:23.410151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.071052Z","time spent":"339.092907ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T19:51:23.410147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.852196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-15T19:51:23.410189Z","caller":"traceutil/trace.go:171","msg":"trace[708759188] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24817; }","duration":"344.935459ms","start":"2026-06-15T19:51:23.065238Z","end":"2026-06-15T19:51:23.410174Z","steps":["trace[708759188] 'agreement among raft nodes before linearized reading' (duration: 344.746383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:23.410231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.065215Z","time spent":"345.007801ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-15T19:51:23.410408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.07917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-15T19:51:23.410545Z","caller":"traceutil/trace.go:171","msg":"trace[1869846916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24817; }","duration":"329.259645ms","start":"2026-06-15T19:51:23.081261Z","end":"2026-06-15T19:51:23.41052Z","steps":["trace[1869846916] 'agreement among raft nodes before linearized reading' (duration: 328.731891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:23.410621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.081248Z","time spent":"329.360508ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T19:51:23.668807Z","caller":"traceutil/trace.go:171","msg":"trace[1758806477] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"254.124857ms","start":"2026-06-15T19:51:23.414658Z","end":"2026-06-15T19:51:23.668783Z","steps":["trace[1758806477] 'process raft request' (duration: 253.251373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:23.962879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.638526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:51:23.963018Z","caller":"traceutil/trace.go:171","msg":"trace[113683385] linearizableReadLoop","detail":"{readStateIndex:25632; appliedIndex:25629; }","duration":"305.671983ms","start":"2026-06-15T19:51:23.657331Z","end":"2026-06-15T19:51:23.963003Z","steps":["trace[113683385] 'read index received' (duration: 10.611154ms)","trace[113683385] 'applied index is now lower than readState.Index' (duration: 295.059879ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:23.963082Z","caller":"traceutil/trace.go:171","msg":"trace[602707270] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"547.475499ms","start":"2026-06-15T19:51:23.415598Z","end":"2026-06-15T19:51:23.963073Z","steps":["trace[602707270] 'process raft request' (duration: 358.555935ms)","trace[602707270] 'compare' (duration: 188.527363ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:51:23.96313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.415571Z","time spent":"547.536391ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:51:23.963184Z","caller":"traceutil/trace.go:171","msg":"trace[888279123] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"546.085631ms","start":"2026-06-15T19:51:23.417015Z","end":"2026-06-15T19:51:23.963101Z","steps":["trace[888279123] 'process raft request' (duration: 545.946377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:23.963224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.874718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:51:23.963294Z","caller":"traceutil/trace.go:171","msg":"trace[890017331] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24820; }","duration":"305.998682ms","start":"2026-06-15T19:51:23.657278Z","end":"2026-06-15T19:51:23.963277Z","steps":["trace[890017331] 'agreement among raft nodes before linearized reading' (duration: 305.819027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:23.963346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.657256Z","time spent":"306.080814ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:51:23.963357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.416998Z","time spent":"546.282737ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:51:24.026893Z","caller":"traceutil/trace.go:171","msg":"trace[1045317059] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"241.643661ms","start":"2026-06-15T19:51:23.785231Z","end":"2026-06-15T19:51:24.026874Z","steps":["trace[1045317059] 'process raft request' (duration: 241.397815ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:24.257954Z","caller":"traceutil/trace.go:171","msg":"trace[124192450] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"424.685973ms","start":"2026-06-15T19:51:23.833239Z","end":"2026-06-15T19:51:24.257925Z","steps":["trace[124192450] 'process raft request' (duration: 424.420826ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:24.257967Z","caller":"traceutil/trace.go:171","msg":"trace[892638348] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"417.521825ms","start":"2026-06-15T19:51:23.840418Z","end":"2026-06-15T19:51:24.25794Z","steps":["trace[892638348] 'process raft request' (duration: 417.421493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:24.258111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.833185Z","time spent":"424.836088ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:51:24.258112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.840404Z","time spent":"417.626758ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:51:24.258203Z","caller":"traceutil/trace.go:171","msg":"trace[532775876] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"403.526768ms","start":"2026-06-15T19:51:23.854641Z","end":"2026-06-15T19:51:24.258167Z","steps":["trace[532775876] 'process raft request' (duration: 403.24791ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:24.258322Z","caller":"traceutil/trace.go:171","msg":"trace[1085919185] linearizableReadLoop","detail":"{readStateIndex:25636; appliedIndex:25632; }","duration":"295.263644ms","start":"2026-06-15T19:51:23.963043Z","end":"2026-06-15T19:51:24.258307Z","steps":["trace[1085919185] 'read index received' (duration: 63.591108ms)","trace[1085919185] 'applied index is now lower than readState.Index' (duration: 231.671536ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:51:24.258403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.854627Z","time spent":"403.664982ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:51:24.258381Z","caller":"traceutil/trace.go:171","msg":"trace[150686041] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"249.640683ms","start":"2026-06-15T19:51:24.008723Z","end":"2026-06-15T19:51:24.258364Z","steps":["trace[150686041] 'process raft request' (duration: 249.425137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:24.258467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.221917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:51:24.258504Z","caller":"traceutil/trace.go:171","msg":"trace[1886320820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24825; }","duration":"196.292579ms","start":"2026-06-15T19:51:24.062202Z","end":"2026-06-15T19:51:24.258494Z","steps":["trace[1886320820] 'agreement among raft nodes before linearized reading' (duration: 196.226317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:24.258516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.691399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-15T19:51:24.258574Z","caller":"traceutil/trace.go:171","msg":"trace[991146317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24825; }","duration":"396.779631ms","start":"2026-06-15T19:51:23.861782Z","end":"2026-06-15T19:51:24.258562Z","steps":["trace[991146317] 'agreement among raft nodes before linearized reading' (duration: 396.626527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:24.258613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:23.861769Z","time spent":"396.835663ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T19:51:25.779376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.199147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-15T19:51:25.779498Z","caller":"traceutil/trace.go:171","msg":"trace[1878900964] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24832; }","duration":"106.369231ms","start":"2026-06-15T19:51:25.673095Z","end":"2026-06-15T19:51:25.779464Z","steps":["trace[1878900964] 'range keys from in-memory index tree' (duration: 105.971561ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:25.931004Z","caller":"traceutil/trace.go:171","msg":"trace[789293675] transaction","detail":"{read_only:false; response_revision:24833; number_of_response:1; }","duration":"146.425789ms","start":"2026-06-15T19:51:25.784554Z","end":"2026-06-15T19:51:25.930979Z","steps":["trace[789293675] 'process raft request' (duration: 146.279155ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:26.076254Z","caller":"traceutil/trace.go:171","msg":"trace[1341019560] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"105.793375ms","start":"2026-06-15T19:51:25.970421Z","end":"2026-06-15T19:51:26.076214Z","steps":["trace[1341019560] 'process raft request' (duration: 33.966499ms)","trace[1341019560] 'compare' (duration: 71.312292ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:26.133866Z","caller":"traceutil/trace.go:171","msg":"trace[922603050] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"162.127424ms","start":"2026-06-15T19:51:25.971702Z","end":"2026-06-15T19:51:26.13383Z","steps":["trace[922603050] 'process raft request' (duration: 161.827915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:26.30334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.170059ms","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-06-15T19:51:26.303445Z","caller":"traceutil/trace.go:171","msg":"trace[1770542970] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:24835; }","duration":"126.332193ms","start":"2026-06-15T19:51:26.177088Z","end":"2026-06-15T19:51:26.30342Z","steps":["trace[1770542970] 'count revisions from in-memory index tree' (duration: 126.085797ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:26.396177Z","caller":"traceutil/trace.go:171","msg":"trace[930540987] transaction","detail":"{read_only:false; response_revision:24836; number_of_response:1; }","duration":"132.949747ms","start":"2026-06-15T19:51:26.263208Z","end":"2026-06-15T19:51:26.396158Z","steps":["trace[930540987] 'process raft request' (duration: 132.826473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:26.53269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.091413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:51:26.533424Z","caller":"traceutil/trace.go:171","msg":"trace[2044894321] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"269.266016ms","start":"2026-06-15T19:51:26.264139Z","end":"2026-06-15T19:51:26.533405Z","steps":["trace[2044894321] 'process raft request' (duration: 140.354491ms)","trace[2044894321] 'compare' (duration: 127.98321ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:26.533423Z","caller":"traceutil/trace.go:171","msg":"trace[129757437] linearizableReadLoop","detail":"{readStateIndex:25650; appliedIndex:25648; }","duration":"245.058277ms","start":"2026-06-15T19:51:26.288354Z","end":"2026-06-15T19:51:26.533412Z","steps":["trace[129757437] 'read index received' (duration: 107.887883ms)","trace[129757437] 'applied index is now lower than readState.Index' (duration: 137.169444ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:26.533551Z","caller":"traceutil/trace.go:171","msg":"trace[1122729365] transaction","detail":"{read_only:false; response_revision:24838; number_of_response:1; }","duration":"268.602868ms","start":"2026-06-15T19:51:26.264904Z","end":"2026-06-15T19:51:26.533507Z","steps":["trace[1122729365] 'process raft request' (duration: 268.428793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:26.533667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.313944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-15T19:51:26.533741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.110035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-15T19:51:26.533748Z","caller":"traceutil/trace.go:171","msg":"trace[439911849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24838; }","duration":"245.441277ms","start":"2026-06-15T19:51:26.288289Z","end":"2026-06-15T19:51:26.53373Z","steps":["trace[439911849] 'agreement among raft nodes before linearized reading' (duration: 245.260102ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:26.533783Z","caller":"traceutil/trace.go:171","msg":"trace[616672627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24838; }","duration":"174.198058ms","start":"2026-06-15T19:51:26.359575Z","end":"2026-06-15T19:51:26.533773Z","steps":["trace[616672627] 'agreement among raft nodes before linearized reading' (duration: 174.018393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:26.849446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.737502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-15T19:51:26.849624Z","caller":"traceutil/trace.go:171","msg":"trace[772648531] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"310.98769ms","start":"2026-06-15T19:51:26.538611Z","end":"2026-06-15T19:51:26.849599Z","steps":["trace[772648531] 'process raft request' (duration: 102.016921ms)","trace[772648531] 'compare' (duration: 208.328471ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:51:26.849716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:26.538596Z","time spent":"311.078152ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:51:26.849765Z","caller":"traceutil/trace.go:171","msg":"trace[652478878] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"311.122333ms","start":"2026-06-15T19:51:26.538623Z","end":"2026-06-15T19:51:26.849746Z","steps":["trace[652478878] 'process raft request' (duration: 310.946998ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:26.849835Z","caller":"traceutil/trace.go:171","msg":"trace[1731563881] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"310.30334ms","start":"2026-06-15T19:51:26.53952Z","end":"2026-06-15T19:51:26.849824Z","steps":["trace[1731563881] 'process raft request' (duration: 310.174567ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:26.849848Z","caller":"traceutil/trace.go:171","msg":"trace[79981924] linearizableReadLoop","detail":"{readStateIndex:25655; appliedIndex:25651; }","duration":"162.00414ms","start":"2026-06-15T19:51:26.68782Z","end":"2026-06-15T19:51:26.849825Z","steps":["trace[79981924] 'read index received' (duration: 18.335917ms)","trace[79981924] 'applied index is now lower than readState.Index' (duration: 143.667503ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:51:26.849888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:26.539482Z","time spent":"310.375513ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:51:26.849887Z","caller":"traceutil/trace.go:171","msg":"trace[953333477] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"285.058073ms","start":"2026-06-15T19:51:26.564813Z","end":"2026-06-15T19:51:26.849871Z","steps":["trace[953333477] 'process raft request' (duration: 284.934479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:26.850005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.187935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:51:26.850042Z","caller":"traceutil/trace.go:171","msg":"trace[488025610] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24842; }","duration":"162.262587ms","start":"2026-06-15T19:51:26.687769Z","end":"2026-06-15T19:51:26.850032Z","steps":["trace[488025610] 'agreement among raft nodes before linearized reading' (duration: 162.178145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:26.850084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.715408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-15T19:51:26.850008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.346317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T19:51:26.850174Z","caller":"traceutil/trace.go:171","msg":"trace[670424125] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:24842; }","duration":"133.548313ms","start":"2026-06-15T19:51:26.716615Z","end":"2026-06-15T19:51:26.850164Z","steps":["trace[670424125] 'agreement among raft nodes before linearized reading' (duration: 133.359457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:26.85026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:51:26.538611Z","time spent":"311.224077ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:51:26.851543Z","caller":"traceutil/trace.go:171","msg":"trace[784221941] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:24842; }","duration":"105.171318ms","start":"2026-06-15T19:51:26.746358Z","end":"2026-06-15T19:51:26.851529Z","steps":["trace[784221941] 'agreement among raft nodes before linearized reading' (duration: 103.673757ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:27.618207Z","caller":"traceutil/trace.go:171","msg":"trace[592483783] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"109.762515ms","start":"2026-06-15T19:51:27.508421Z","end":"2026-06-15T19:51:27.618184Z","steps":["trace[592483783] 'process raft request' (duration: 34.938017ms)","trace[592483783] 'compare' (duration: 74.574681ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:27.798478Z","caller":"traceutil/trace.go:171","msg":"trace[891972898] transaction","detail":"{read_only:false; response_revision:24845; number_of_response:1; }","duration":"289.33419ms","start":"2026-06-15T19:51:27.509118Z","end":"2026-06-15T19:51:27.798452Z","steps":["trace[891972898] 'process raft request' (duration: 249.009615ms)","trace[891972898] 'compare' (duration: 39.873952ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:27.798871Z","caller":"traceutil/trace.go:171","msg":"trace[469650658] transaction","detail":"{read_only:false; response_revision:24846; number_of_response:1; }","duration":"289.713731ms","start":"2026-06-15T19:51:27.509144Z","end":"2026-06-15T19:51:27.798857Z","steps":["trace[469650658] 'process raft request' (duration: 289.074203ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:27.799063Z","caller":"traceutil/trace.go:171","msg":"trace[1261944149] linearizableReadLoop","detail":"{readStateIndex:25659; appliedIndex:25657; }","duration":"227.721636ms","start":"2026-06-15T19:51:27.571312Z","end":"2026-06-15T19:51:27.799034Z","steps":["trace[1261944149] 'read index received' (duration: 186.777034ms)","trace[1261944149] 'applied index is now lower than readState.Index' (duration: 40.940872ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:51:27.799261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.918581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-15T19:51:27.799308Z","caller":"traceutil/trace.go:171","msg":"trace[865999803] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24846; }","duration":"228.018334ms","start":"2026-06-15T19:51:27.571278Z","end":"2026-06-15T19:51:27.799296Z","steps":["trace[865999803] 'agreement among raft nodes before linearized reading' (duration: 227.905851ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:28.11939Z","caller":"traceutil/trace.go:171","msg":"trace[1298546551] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"152.180958ms","start":"2026-06-15T19:51:27.967181Z","end":"2026-06-15T19:51:28.119362Z","steps":["trace[1298546551] 'process raft request' (duration: 151.818928ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:28.240592Z","caller":"traceutil/trace.go:171","msg":"trace[296509096] linearizableReadLoop","detail":"{readStateIndex:25661; appliedIndex:25660; }","duration":"121.417338ms","start":"2026-06-15T19:51:28.119147Z","end":"2026-06-15T19:51:28.240564Z","steps":["trace[296509096] 'read index received' (duration: 110.588038ms)","trace[296509096] 'applied index is now lower than readState.Index' (duration: 10.82798ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:51:28.240976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.839447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-15T19:51:28.240961Z","caller":"traceutil/trace.go:171","msg":"trace[754227738] transaction","detail":"{read_only:false; response_revision:24848; number_of_response:1; }","duration":"129.893122ms","start":"2026-06-15T19:51:28.11104Z","end":"2026-06-15T19:51:28.240933Z","steps":["trace[754227738] 'process raft request' (duration: 118.637321ms)","trace[754227738] 'compare' (duration: 10.469339ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:28.241022Z","caller":"traceutil/trace.go:171","msg":"trace[212870601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24848; }","duration":"152.928349ms","start":"2026-06-15T19:51:28.088083Z","end":"2026-06-15T19:51:28.241011Z","steps":["trace[212870601] 'agreement among raft nodes before linearized reading' (duration: 152.755545ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:28.706761Z","caller":"traceutil/trace.go:171","msg":"trace[1878773607] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"168.303494ms","start":"2026-06-15T19:51:28.538431Z","end":"2026-06-15T19:51:28.706734Z","steps":["trace[1878773607] 'process raft request' (duration: 70.014066ms)","trace[1878773607] 'compare' (duration: 98.02005ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:28.706976Z","caller":"traceutil/trace.go:171","msg":"trace[185863113] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"168.001445ms","start":"2026-06-15T19:51:28.538929Z","end":"2026-06-15T19:51:28.706931Z","steps":["trace[185863113] 'process raft request' (duration: 167.757598ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:29.073609Z","caller":"traceutil/trace.go:171","msg":"trace[1655090658] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"214.866952ms","start":"2026-06-15T19:51:28.858695Z","end":"2026-06-15T19:51:29.073562Z","steps":["trace[1655090658] 'process raft request' (duration: 148.788195ms)","trace[1655090658] 'compare' (duration: 65.84951ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:30.610493Z","caller":"traceutil/trace.go:171","msg":"trace[1376569477] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"116.627385ms","start":"2026-06-15T19:51:30.493835Z","end":"2026-06-15T19:51:30.610463Z","steps":["trace[1376569477] 'process raft request' (duration: 116.43753ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:30.825842Z","caller":"traceutil/trace.go:171","msg":"trace[235065734] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"113.887569ms","start":"2026-06-15T19:51:30.711918Z","end":"2026-06-15T19:51:30.825805Z","steps":["trace[235065734] 'process raft request' (duration: 113.361315ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:51.28054Z","caller":"traceutil/trace.go:171","msg":"trace[204028633] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"107.959415ms","start":"2026-06-15T19:51:51.172462Z","end":"2026-06-15T19:51:51.280421Z","steps":["trace[204028633] 'process raft request' (duration: 107.76391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:51.593589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.253583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T19:51:51.593678Z","caller":"traceutil/trace.go:171","msg":"trace[1811342792] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25028; }","duration":"104.394217ms","start":"2026-06-15T19:51:51.489263Z","end":"2026-06-15T19:51:51.593657Z","steps":["trace[1811342792] 'count revisions from in-memory index tree' (duration: 104.1532ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:51.749457Z","caller":"traceutil/trace.go:171","msg":"trace[1710081640] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"129.832411ms","start":"2026-06-15T19:51:51.619595Z","end":"2026-06-15T19:51:51.749427Z","steps":["trace[1710081640] 'process raft request' (duration: 129.433929ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:51:51.99262Z","caller":"traceutil/trace.go:171","msg":"trace[1172864223] linearizableReadLoop","detail":"{readStateIndex:25847; appliedIndex:25846; }","duration":"171.159753ms","start":"2026-06-15T19:51:51.82144Z","end":"2026-06-15T19:51:51.9926Z","steps":["trace[1172864223] 'read index received' (duration: 93.576558ms)","trace[1172864223] 'applied index is now lower than readState.Index' (duration: 77.581945ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:51:51.99271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.263276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-15T19:51:51.992706Z","caller":"traceutil/trace.go:171","msg":"trace[1620516059] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"192.226106ms","start":"2026-06-15T19:51:51.800436Z","end":"2026-06-15T19:51:51.992662Z","steps":["trace[1620516059] 'process raft request' (duration: 114.581499ms)","trace[1620516059] 'compare' (duration: 77.192784ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:51:51.99273Z","caller":"traceutil/trace.go:171","msg":"trace[1154727523] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25032; }","duration":"171.335438ms","start":"2026-06-15T19:51:51.821387Z","end":"2026-06-15T19:51:51.992722Z","steps":["trace[1154727523] 'agreement among raft nodes before linearized reading' (duration: 171.276986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:52.629382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.647324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-15T19:51:52.629475Z","caller":"traceutil/trace.go:171","msg":"trace[1963683679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25033; }","duration":"198.788267ms","start":"2026-06-15T19:51:52.430665Z","end":"2026-06-15T19:51:52.629453Z","steps":["trace[1963683679] 'range keys from in-memory index tree' (duration: 198.398106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:51:52.629451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.149847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-15T19:51:52.629546Z","caller":"traceutil/trace.go:171","msg":"trace[1327527251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25033; }","duration":"208.29252ms","start":"2026-06-15T19:51:52.421233Z","end":"2026-06-15T19:51:52.629525Z","steps":["trace[1327527251] 'range keys from in-memory index tree' (duration: 207.95353ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:54:03.479863Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23453} {"level":"info","ts":"2026-06-15T19:54:03.624342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23453,"took":"138.705026ms","hash":1592367465,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":37085184,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-15T19:54:03.62443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1592367465,"revision":23453,"compact-revision":20315} {"level":"info","ts":"2026-06-15T19:54:12.529664Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a8f.wal"} {"level":"info","ts":"2026-06-15T19:59:03.485786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26353} {"level":"info","ts":"2026-06-15T19:59:03.624518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26353,"took":"135.650555ms","hash":1613165568,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":32403456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-15T19:59:03.624568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1613165568,"revision":26353,"compact-revision":23453} {"level":"info","ts":"2026-06-15T19:59:33.560163Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"185293d8d40ffce0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-15T19:59:33.606373Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-15T19:59:33.60649Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-15T20:04:03.492272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28858} {"level":"info","ts":"2026-06-15T20:04:03.624989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28858,"took":"128.974259ms","hash":2510003537,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":30449664,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-15T20:04:03.625062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2510003537,"revision":28858,"compact-revision":26353} {"level":"info","ts":"2026-06-15T20:09:03.498039Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31142} {"level":"info","ts":"2026-06-15T20:09:03.626139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31142,"took":"123.40524ms","hash":4013233367,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":30646272,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-15T20:09:03.626318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4013233367,"revision":31142,"compact-revision":28858} {"level":"info","ts":"2026-06-15T20:09:22.587585Z","caller":"traceutil/trace.go:171","msg":"trace[843185131] transaction","detail":"{read_only:false; response_revision:33633; number_of_response:1; }","duration":"237.125392ms","start":"2026-06-15T20:09:22.350431Z","end":"2026-06-15T20:09:22.587557Z","steps":["trace[843185131] 'process raft request' (duration: 237.007598ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:14:03.503551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33468} {"level":"info","ts":"2026-06-15T20:14:03.649376Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33468,"took":"142.674384ms","hash":942188741,"current-db-size-bytes":59736064,"current-db-size":"60 MB","current-db-size-in-use-bytes":32661504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-15T20:14:03.649461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":942188741,"revision":33468,"compact-revision":31142} {"level":"info","ts":"2026-06-15T20:19:03.512243Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35732} {"level":"info","ts":"2026-06-15T20:19:03.660753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35732,"took":"144.148475ms","hash":158275503,"current-db-size-bytes":61480960,"current-db-size":"62 MB","current-db-size-in-use-bytes":31629312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-15T20:19:03.660806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":158275503,"revision":35732,"compact-revision":33468} {"level":"info","ts":"2026-06-15T20:20:45.510701Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"185293d8d40ffce0","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-15T20:20:45.514855Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-15T20:20:45.514934Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}