{"level":"warn","ts":"2026-07-15T04:59:17.812907Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:59:17.813043Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-07-15T04:59:17.813136Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:59:17.813144Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-15T04:59:17.813201Z","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-07-15T04:59:17.813945Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-07-15T04:59:17.814028Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-07-15T04:59:17.820151Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.15548ms"} {"level":"info","ts":"2026-07-15T04:59:17.8271Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-07-15T04:59:17.82721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T04:59:17.827243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-07-15T04:59:17.827262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T04:59:17.827277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-07-15T04:59:17.827332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-07-15T04:59:17.831456Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T04:59:17.833889Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T04:59:17.835143Z","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-07-15T04:59:17.837138Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T04:59:17.838734Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T04:59:17.838806Z","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-07-15T04:59:17.838924Z","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-07-15T04:59:17.838941Z","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-07-15T04:59:17.841291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-07-15T04:59:17.841515Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-15T04:59:17.842313Z","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-07-15T04:59:17.842387Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-15T04:59:17.842411Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-15T04:59:17.842758Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T04:59:17.842677Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T04:59:18.428265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T04:59:18.42844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T04:59:18.4285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-07-15T04:59:18.428544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-07-15T04:59:18.42858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-15T04:59:18.428621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-07-15T04:59:18.428667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-15T04:59:18.42978Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:59:18.430547Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:59:18.430547Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T04:59:18.430588Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:59:18.430927Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T04:59:18.431003Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T04:59:18.431165Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:59:18.431372Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:59:18.431425Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:59:18.434066Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T04:59:18.434276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-07-15T04:59:32.620428Z","caller":"traceutil/trace.go:171","msg":"trace[1659737531] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"131.31721ms","start":"2026-07-15T04:59:32.489087Z","end":"2026-07-15T04:59:32.620404Z","steps":["trace[1659737531] 'process raft request' (duration: 131.175768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.022869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.083392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:59:59.022996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.76606ms","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":"warn","ts":"2026-07-15T04:59:59.022984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.341488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.023052Z","caller":"traceutil/trace.go:171","msg":"trace[1086883678] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:623; }","duration":"198.831102ms","start":"2026-07-15T04:59:58.824199Z","end":"2026-07-15T04:59:59.02303Z","steps":["trace[1086883678] 'count revisions from in-memory index tree' (duration: 198.72573ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:59:59.02307Z","caller":"traceutil/trace.go:171","msg":"trace[777459995] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"136.473551ms","start":"2026-07-15T04:59:58.886573Z","end":"2026-07-15T04:59:59.023046Z","steps":["trace[777459995] 'range keys from in-memory index tree' (duration: 136.268057ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:59:59.023008Z","caller":"traceutil/trace.go:171","msg":"trace[1687162549] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"136.267686ms","start":"2026-07-15T04:59:58.88672Z","end":"2026-07-15T04:59:59.022987Z","steps":["trace[1687162549] 'count revisions from in-memory index tree' (duration: 135.9929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.023066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.315017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.023183Z","caller":"traceutil/trace.go:171","msg":"trace[1465142071] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"136.469959ms","start":"2026-07-15T04:59:58.886694Z","end":"2026-07-15T04:59:59.023164Z","steps":["trace[1465142071] 'range keys from in-memory index tree' (duration: 136.268306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.022926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.24221ms","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-07-15T04:59:59.022976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.882703ms","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-07-15T04:59:59.023313Z","caller":"traceutil/trace.go:171","msg":"trace[124853911] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:623; }","duration":"198.641988ms","start":"2026-07-15T04:59:58.824613Z","end":"2026-07-15T04:59:59.023255Z","steps":["trace[124853911] 'range keys from in-memory index tree' (duration: 198.193099ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:59:59.023337Z","caller":"traceutil/trace.go:171","msg":"trace[1377242206] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:623; }","duration":"199.29965ms","start":"2026-07-15T04:59:58.824013Z","end":"2026-07-15T04:59:59.023312Z","steps":["trace[1377242206] 'range keys from in-memory index tree' (duration: 198.80215ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:59:59.111426Z","caller":"traceutil/trace.go:171","msg":"trace[1018744844] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"176.160981ms","start":"2026-07-15T04:59:58.935241Z","end":"2026-07-15T04:59:59.111402Z","steps":["trace[1018744844] 'read index received' (duration: 175.313284ms)","trace[1018744844] 'applied index is now lower than readState.Index' (duration: 846.777µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:59:59.112219Z","caller":"traceutil/trace.go:171","msg":"trace[1292194660] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"179.391303ms","start":"2026-07-15T04:59:58.932451Z","end":"2026-07-15T04:59:59.111842Z","steps":["trace[1292194660] 'process raft request' (duration: 178.427085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.112463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.14642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.112595Z","caller":"traceutil/trace.go:171","msg":"trace[1947640470] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:624; }","duration":"177.364064ms","start":"2026-07-15T04:59:58.935202Z","end":"2026-07-15T04:59:59.112566Z","steps":["trace[1947640470] 'agreement among raft nodes before linearized reading' (duration: 177.102419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.113348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.057952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.113446Z","caller":"traceutil/trace.go:171","msg":"trace[1117185656] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:624; }","duration":"134.172624ms","start":"2026-07-15T04:59:58.979233Z","end":"2026-07-15T04:59:59.113405Z","steps":["trace[1117185656] 'agreement among raft nodes before linearized reading' (duration: 134.023361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.113442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.175473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:59:59.11348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.395024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.113587Z","caller":"traceutil/trace.go:171","msg":"trace[14690032] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"153.340657ms","start":"2026-07-15T04:59:58.960202Z","end":"2026-07-15T04:59:59.113542Z","steps":["trace[14690032] 'agreement among raft nodes before linearized reading' (duration: 153.079952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.113608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.368538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.11364Z","caller":"traceutil/trace.go:171","msg":"trace[1070983311] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:624; }","duration":"177.559967ms","start":"2026-07-15T04:59:58.93601Z","end":"2026-07-15T04:59:59.11357Z","steps":["trace[1070983311] 'agreement among raft nodes before linearized reading' (duration: 177.302502ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:59:59.113739Z","caller":"traceutil/trace.go:171","msg":"trace[1837902263] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"153.520591ms","start":"2026-07-15T04:59:58.960157Z","end":"2026-07-15T04:59:59.113678Z","steps":["trace[1837902263] 'agreement among raft nodes before linearized reading' (duration: 153.219574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.114093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.038163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:59:59.114118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.760416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.114175Z","caller":"traceutil/trace.go:171","msg":"trace[867581457] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:624; }","duration":"134.133714ms","start":"2026-07-15T04:59:58.980007Z","end":"2026-07-15T04:59:59.11414Z","steps":["trace[867581457] 'agreement among raft nodes before linearized reading' (duration: 133.629424ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:59:59.11423Z","caller":"traceutil/trace.go:171","msg":"trace[457284201] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:624; }","duration":"134.912079ms","start":"2026-07-15T04:59:58.979283Z","end":"2026-07-15T04:59:59.114195Z","steps":["trace[457284201] 'agreement among raft nodes before linearized reading' (duration: 134.360369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.114308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.601203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.114399Z","caller":"traceutil/trace.go:171","msg":"trace[1416749091] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:624; }","duration":"153.685414ms","start":"2026-07-15T04:59:58.96068Z","end":"2026-07-15T04:59:59.114365Z","steps":["trace[1416749091] 'agreement among raft nodes before linearized reading' (duration: 153.403869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.115083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.833132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.115161Z","caller":"traceutil/trace.go:171","msg":"trace[410741850] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:624; }","duration":"179.938104ms","start":"2026-07-15T04:59:58.935193Z","end":"2026-07-15T04:59:59.115131Z","steps":["trace[410741850] 'agreement among raft nodes before linearized reading' (duration: 176.356555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.115204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.249082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.115297Z","caller":"traceutil/trace.go:171","msg":"trace[1764741599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"142.357364ms","start":"2026-07-15T04:59:58.972897Z","end":"2026-07-15T04:59:59.115255Z","steps":["trace[1764741599] 'agreement among raft nodes before linearized reading' (duration: 140.542499ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:59:59.593846Z","caller":"traceutil/trace.go:171","msg":"trace[1633738028] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"424.637186ms","start":"2026-07-15T04:59:59.169191Z","end":"2026-07-15T04:59:59.593829Z","steps":["trace[1633738028] 'process raft request' (duration: 423.500374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.593881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.928311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.593923Z","caller":"traceutil/trace.go:171","msg":"trace[1556590008] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:626; }","duration":"426.009012ms","start":"2026-07-15T04:59:59.167903Z","end":"2026-07-15T04:59:59.593912Z","steps":["trace[1556590008] 'agreement among raft nodes before linearized reading' (duration: 425.90226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.593921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.003573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:59:59.593952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.016414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:59:59.593951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:59:59.167891Z","time spent":"426.052133ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-07-15T04:59:59.594009Z","caller":"traceutil/trace.go:171","msg":"trace[1668581066] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:626; }","duration":"441.091125ms","start":"2026-07-15T04:59:59.152905Z","end":"2026-07-15T04:59:59.593996Z","steps":["trace[1668581066] 'agreement among raft nodes before linearized reading' (duration: 441.003403ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:59:59.594008Z","caller":"traceutil/trace.go:171","msg":"trace[808981467] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:626; }","duration":"441.123596ms","start":"2026-07-15T04:59:59.152866Z","end":"2026-07-15T04:59:59.59399Z","steps":["trace[808981467] 'agreement among raft nodes before linearized reading' (duration: 440.981313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.59406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:59:59.152901Z","time spent":"441.148796ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-15T04:59:59.594059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:59:59.15285Z","time spent":"441.194917ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-07-15T04:59:59.594131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.324526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:59:59.594129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.657736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gx7db\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-15T04:59:59.594168Z","caller":"traceutil/trace.go:171","msg":"trace[1256065091] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:626; }","duration":"465.374347ms","start":"2026-07-15T04:59:59.128784Z","end":"2026-07-15T04:59:59.594158Z","steps":["trace[1256065091] 'agreement among raft nodes before linearized reading' (duration: 465.305335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.594185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.114934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:59:59.594212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:59:59.128778Z","time spent":"465.426798ms","remote":"127.0.0.1:43064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-07-15T04:59:59.594205Z","caller":"traceutil/trace.go:171","msg":"trace[1757941408] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gx7db; range_end:; response_count:1; response_revision:626; }","duration":"459.772637ms","start":"2026-07-15T04:59:59.134415Z","end":"2026-07-15T04:59:59.594188Z","steps":["trace[1757941408] 'agreement among raft nodes before linearized reading' (duration: 459.459851ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:59:59.594215Z","caller":"traceutil/trace.go:171","msg":"trace[152622017] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:626; }","duration":"426.145375ms","start":"2026-07-15T04:59:59.16806Z","end":"2026-07-15T04:59:59.594206Z","steps":["trace[152622017] 'agreement among raft nodes before linearized reading' (duration: 426.101804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.594244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:59:59.168002Z","time spent":"426.235356ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-15T04:59:59.594173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.593534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.594274Z","caller":"traceutil/trace.go:171","msg":"trace[671991832] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:626; }","duration":"425.703046ms","start":"2026-07-15T04:59:59.168563Z","end":"2026-07-15T04:59:59.594266Z","steps":["trace[671991832] 'agreement among raft nodes before linearized reading' (duration: 425.591274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.594299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:59:59.168555Z","time spent":"425.736447ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-07-15T04:59:59.594517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.985528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:59:59.594554Z","caller":"traceutil/trace.go:171","msg":"trace[343735765] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:626; }","duration":"466.034299ms","start":"2026-07-15T04:59:59.128511Z","end":"2026-07-15T04:59:59.594545Z","steps":["trace[343735765] 'agreement among raft nodes before linearized reading' (duration: 465.975448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:59:59.594583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:59:59.128503Z","time spent":"466.07315ms","remote":"127.0.0.1:43064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-15T04:59:59.594475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:59:59.169163Z","time spent":"424.747058ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T04:59:59.594686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:59:59.134399Z","time spent":"460.256627ms","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-gx7db\" "} {"level":"info","ts":"2026-07-15T04:59:59.593775Z","caller":"traceutil/trace.go:171","msg":"trace[685525290] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"415.433856ms","start":"2026-07-15T04:59:59.178316Z","end":"2026-07-15T04:59:59.59375Z","steps":["trace[685525290] 'read index received' (duration: 414.292123ms)","trace[685525290] 'applied index is now lower than readState.Index' (duration: 1.140753ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:31.33708Z","caller":"traceutil/trace.go:171","msg":"trace[657256897] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"233.755886ms","start":"2026-07-15T05:01:31.103291Z","end":"2026-07-15T05:01:31.337047Z","steps":["trace[657256897] 'process raft request' (duration: 190.572364ms)","trace[657256897] 'compare' (duration: 43.045609ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:01:31.716272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.786325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:01:31.716371Z","caller":"traceutil/trace.go:171","msg":"trace[2051435593] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"255.489915ms","start":"2026-07-15T05:01:31.460865Z","end":"2026-07-15T05:01:31.716355Z","steps":["trace[2051435593] 'read index received' (duration: 4.19616ms)","trace[2051435593] 'applied index is now lower than readState.Index' (duration: 251.292605ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:31.716484Z","caller":"traceutil/trace.go:171","msg":"trace[143893710] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"279.56035ms","start":"2026-07-15T05:01:31.436882Z","end":"2026-07-15T05:01:31.716442Z","steps":["trace[143893710] 'process raft request' (duration: 28.264285ms)","trace[143893710] 'compare' (duration: 250.281414ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:01:31.716574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.493648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2ec21a75-5724-44fb-8447-ab1b43e332a4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-15T05:01:31.716606Z","caller":"traceutil/trace.go:171","msg":"trace[1240906817] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2ec21a75-5724-44fb-8447-ab1b43e332a4; range_end:; response_count:1; response_revision:1859; }","duration":"141.559709ms","start":"2026-07-15T05:01:31.575036Z","end":"2026-07-15T05:01:31.716596Z","steps":["trace[1240906817] 'agreement among raft nodes before linearized reading' (duration: 141.417027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:31.716634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.7467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T05:01:31.716707Z","caller":"traceutil/trace.go:171","msg":"trace[1358092794] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"255.863642ms","start":"2026-07-15T05:01:31.460825Z","end":"2026-07-15T05:01:31.716689Z","steps":["trace[1358092794] 'agreement among raft nodes before linearized reading' (duration: 255.606037ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:40.886768Z","caller":"traceutil/trace.go:171","msg":"trace[6634969] linearizableReadLoop","detail":"{readStateIndex:2015; appliedIndex:2014; }","duration":"284.668756ms","start":"2026-07-15T05:01:40.602065Z","end":"2026-07-15T05:01:40.886734Z","steps":["trace[6634969] 'read index received' (duration: 284.467612ms)","trace[6634969] 'applied index is now lower than readState.Index' (duration: 200.014µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:40.886858Z","caller":"traceutil/trace.go:171","msg":"trace[1982486394] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"319.12837ms","start":"2026-07-15T05:01:40.567709Z","end":"2026-07-15T05:01:40.886838Z","steps":["trace[1982486394] 'process raft request' (duration: 318.800994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:40.887066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.985982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T05:01:40.887295Z","caller":"traceutil/trace.go:171","msg":"trace[1543007037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1942; }","duration":"285.096004ms","start":"2026-07-15T05:01:40.602002Z","end":"2026-07-15T05:01:40.887098Z","steps":["trace[1543007037] 'agreement among raft nodes before linearized reading' (duration: 284.91208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:40.887302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:01:40.567688Z","time spent":"319.298224ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:01:41.218566Z","caller":"traceutil/trace.go:171","msg":"trace[714451187] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"354.869809ms","start":"2026-07-15T05:01:40.863668Z","end":"2026-07-15T05:01:41.218538Z","steps":["trace[714451187] 'process raft request' (duration: 288.93681ms)","trace[714451187] 'compare' (duration: 65.710574ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:41.218569Z","caller":"traceutil/trace.go:171","msg":"trace[1737453130] linearizableReadLoop","detail":"{readStateIndex:2016; appliedIndex:2015; }","duration":"331.641731ms","start":"2026-07-15T05:01:40.886892Z","end":"2026-07-15T05:01:41.218534Z","steps":["trace[1737453130] 'read index received' (duration: 265.644311ms)","trace[1737453130] 'applied index is now lower than readState.Index' (duration: 65.99594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:01:41.218743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:01:40.863643Z","time spent":"355.001081ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:01:41.218781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.266671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-15T05:01:41.218789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.382692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-15T05:01:41.218939Z","caller":"traceutil/trace.go:171","msg":"trace[770319034] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1943; }","duration":"362.535994ms","start":"2026-07-15T05:01:40.856369Z","end":"2026-07-15T05:01:41.218905Z","steps":["trace[770319034] 'agreement among raft nodes before linearized reading' (duration: 362.170798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:41.219008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:01:40.856295Z","time spent":"362.698419ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-07-15T05:01:41.218835Z","caller":"traceutil/trace.go:171","msg":"trace[926893267] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1943; }","duration":"440.369253ms","start":"2026-07-15T05:01:40.778452Z","end":"2026-07-15T05:01:41.218822Z","steps":["trace[926893267] 'agreement among raft nodes before linearized reading' (duration: 440.166139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:41.219141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:01:40.778425Z","time spent":"440.681408ms","remote":"127.0.0.1:48942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-15T05:01:41.227111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.300138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:01:41.227119Z","caller":"traceutil/trace.go:171","msg":"trace[1425792750] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"335.265051ms","start":"2026-07-15T05:01:40.891818Z","end":"2026-07-15T05:01:41.227083Z","steps":["trace[1425792750] 'process raft request' (duration: 335.059147ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:41.227194Z","caller":"traceutil/trace.go:171","msg":"trace[1931541035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1945; }","duration":"260.414091ms","start":"2026-07-15T05:01:40.966763Z","end":"2026-07-15T05:01:41.227177Z","steps":["trace[1931541035] 'agreement among raft nodes before linearized reading' (duration: 260.290068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:41.227295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:01:40.891793Z","time spent":"335.411604ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:01:41.227364Z","caller":"traceutil/trace.go:171","msg":"trace[1073433275] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"203.118209ms","start":"2026-07-15T05:01:41.024219Z","end":"2026-07-15T05:01:41.227338Z","steps":["trace[1073433275] 'process raft request' (duration: 202.788003ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:02:18.274092Z","caller":"traceutil/trace.go:171","msg":"trace[261795933] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"213.849564ms","start":"2026-07-15T05:02:18.060213Z","end":"2026-07-15T05:02:18.274062Z","steps":["trace[261795933] 'process raft request' (duration: 213.671211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:02:24.013618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.543657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:02:24.013737Z","caller":"traceutil/trace.go:171","msg":"trace[694205332] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2279; }","duration":"210.072722ms","start":"2026-07-15T05:02:23.803647Z","end":"2026-07-15T05:02:24.013719Z","steps":["trace[694205332] 'read index received' (duration: 87.384632ms)","trace[694205332] 'applied index is now lower than readState.Index' (duration: 122.68684ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:02:24.01375Z","caller":"traceutil/trace.go:171","msg":"trace[277238196] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"218.365581ms","start":"2026-07-15T05:02:23.795352Z","end":"2026-07-15T05:02:24.013718Z","steps":["trace[277238196] 'process raft request' (duration: 95.62921ms)","trace[277238196] 'compare' (duration: 122.439125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:02:24.013888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.245835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T05:02:24.013929Z","caller":"traceutil/trace.go:171","msg":"trace[802535519] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2198; }","duration":"210.320327ms","start":"2026-07-15T05:02:23.803598Z","end":"2026-07-15T05:02:24.013918Z","steps":["trace[802535519] 'agreement among raft nodes before linearized reading' (duration: 210.237135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:03:46.232503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.082482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:03:46.232671Z","caller":"traceutil/trace.go:171","msg":"trace[1063706379] linearizableReadLoop","detail":"{readStateIndex:2947; appliedIndex:2946; }","duration":"249.301644ms","start":"2026-07-15T05:03:45.983351Z","end":"2026-07-15T05:03:46.232652Z","steps":["trace[1063706379] 'read index received' (duration: 80.921096ms)","trace[1063706379] 'applied index is now lower than readState.Index' (duration: 168.378948ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:03:46.232792Z","caller":"traceutil/trace.go:171","msg":"trace[1017944946] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"291.159158ms","start":"2026-07-15T05:03:45.941616Z","end":"2026-07-15T05:03:46.232775Z","steps":["trace[1017944946] 'process raft request' (duration: 122.677999ms)","trace[1017944946] 'compare' (duration: 167.785246ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:03:46.232995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.601399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:03:46.233117Z","caller":"traceutil/trace.go:171","msg":"trace[1360407603] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2839; }","duration":"249.777662ms","start":"2026-07-15T05:03:45.983315Z","end":"2026-07-15T05:03:46.233093Z","steps":["trace[1360407603] 'agreement among raft nodes before linearized reading' (duration: 249.541427ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:03:51.956233Z","caller":"traceutil/trace.go:171","msg":"trace[324416015] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"169.795155ms","start":"2026-07-15T05:03:51.786396Z","end":"2026-07-15T05:03:51.956192Z","steps":["trace[324416015] 'process raft request' (duration: 100.401421ms)","trace[324416015] 'compare' (duration: 69.157129ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:07:32.567757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.460858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:07:32.567836Z","caller":"traceutil/trace.go:171","msg":"trace[1502722232] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4971; }","duration":"124.596421ms","start":"2026-07-15T05:07:32.443224Z","end":"2026-07-15T05:07:32.56782Z","steps":["trace[1502722232] 'range keys from in-memory index tree' (duration: 124.380777ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:07:32.731338Z","caller":"traceutil/trace.go:171","msg":"trace[1228514155] linearizableReadLoop","detail":"{readStateIndex:5159; appliedIndex:5158; }","duration":"159.065732ms","start":"2026-07-15T05:07:32.572248Z","end":"2026-07-15T05:07:32.731313Z","steps":["trace[1228514155] 'read index received' (duration: 158.94313ms)","trace[1228514155] 'applied index is now lower than readState.Index' (duration: 121.672µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:07:32.731387Z","caller":"traceutil/trace.go:171","msg":"trace[162101473] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"160.639152ms","start":"2026-07-15T05:07:32.570709Z","end":"2026-07-15T05:07:32.731348Z","steps":["trace[162101473] 'process raft request' (duration: 160.458968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:07:32.731595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.330017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-15T05:07:32.731668Z","caller":"traceutil/trace.go:171","msg":"trace[469542558] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4972; }","duration":"159.45778ms","start":"2026-07-15T05:07:32.572193Z","end":"2026-07-15T05:07:32.73165Z","steps":["trace[469542558] 'agreement among raft nodes before linearized reading' (duration: 159.229655ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:07:32.750663Z","caller":"traceutil/trace.go:171","msg":"trace[645185823] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"177.641489ms","start":"2026-07-15T05:07:32.572992Z","end":"2026-07-15T05:07:32.750633Z","steps":["trace[645185823] 'process raft request' (duration: 177.459226ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:07:32.750916Z","caller":"traceutil/trace.go:171","msg":"trace[908934964] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"169.072504ms","start":"2026-07-15T05:07:32.581815Z","end":"2026-07-15T05:07:32.750887Z","steps":["trace[908934964] 'process raft request' (duration: 168.773678ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:07:32.751157Z","caller":"traceutil/trace.go:171","msg":"trace[622354415] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"104.029206ms","start":"2026-07-15T05:07:32.647119Z","end":"2026-07-15T05:07:32.751149Z","steps":["trace[622354415] 'process raft request' (duration: 103.686889ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:07:34.908608Z","caller":"traceutil/trace.go:171","msg":"trace[1363557258] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"134.401269ms","start":"2026-07-15T05:07:34.774182Z","end":"2026-07-15T05:07:34.908583Z","steps":["trace[1363557258] 'process raft request' (duration: 134.322257ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:07:34.908718Z","caller":"traceutil/trace.go:171","msg":"trace[43074055] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"131.776759ms","start":"2026-07-15T05:07:34.776934Z","end":"2026-07-15T05:07:34.908711Z","steps":["trace[43074055] 'process raft request' (duration: 131.611126ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:07:34.908617Z","caller":"traceutil/trace.go:171","msg":"trace[463014949] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"152.586487ms","start":"2026-07-15T05:07:34.755997Z","end":"2026-07-15T05:07:34.908583Z","steps":["trace[463014949] 'process raft request' (duration: 112.876895ms)","trace[463014949] 'compare' (duration: 39.492147ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:07:45.547726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.399314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:07:45.547908Z","caller":"traceutil/trace.go:171","msg":"trace[1900994014] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"144.716197ms","start":"2026-07-15T05:07:45.403152Z","end":"2026-07-15T05:07:45.547869Z","steps":["trace[1900994014] 'process raft request' (duration: 31.100268ms)","trace[1900994014] 'compare' (duration: 113.295913ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:07:45.764821Z","caller":"traceutil/trace.go:171","msg":"trace[2076123302] linearizableReadLoop","detail":"{readStateIndex:5431; appliedIndex:5430; }","duration":"130.852411ms","start":"2026-07-15T05:07:45.633943Z","end":"2026-07-15T05:07:45.764795Z","steps":["trace[2076123302] 'read index received' (duration: 58.33165ms)","trace[2076123302] 'applied index is now lower than readState.Index' (duration: 72.519631ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:07:45.764963Z","caller":"traceutil/trace.go:171","msg":"trace[368496230] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"211.700591ms","start":"2026-07-15T05:07:45.553229Z","end":"2026-07-15T05:07:45.76493Z","steps":["trace[368496230] 'process raft request' (duration: 139.057568ms)","trace[368496230] 'compare' (duration: 72.392619ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:07:45.765095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.127796ms","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-07-15T05:07:45.765139Z","caller":"traceutil/trace.go:171","msg":"trace[1569258626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5235; }","duration":"131.219207ms","start":"2026-07-15T05:07:45.633908Z","end":"2026-07-15T05:07:45.765127Z","steps":["trace[1569258626] 'agreement among raft nodes before linearized reading' (duration: 131.028604ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:08:04.858381Z","caller":"traceutil/trace.go:171","msg":"trace[2035010] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"122.833398ms","start":"2026-07-15T05:08:04.735522Z","end":"2026-07-15T05:08:04.858355Z","steps":["trace[2035010] 'process raft request' (duration: 122.683185ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:09:18.45822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3018} {"level":"info","ts":"2026-07-15T05:09:18.544482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3018,"took":"84.586584ms","hash":2935115412,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":32333824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T05:09:18.544584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2935115412,"revision":3018,"compact-revision":-1} {"level":"info","ts":"2026-07-15T05:13:57.548625Z","caller":"traceutil/trace.go:171","msg":"trace[1284797421] transaction","detail":"{read_only:false; response_revision:8221; number_of_response:1; }","duration":"157.712237ms","start":"2026-07-15T05:13:57.390885Z","end":"2026-07-15T05:13:57.548597Z","steps":["trace[1284797421] 'process raft request' (duration: 157.516883ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:14:09.879641Z","caller":"traceutil/trace.go:171","msg":"trace[1632262608] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"125.635712ms","start":"2026-07-15T05:14:09.753983Z","end":"2026-07-15T05:14:09.879618Z","steps":["trace[1632262608] 'process raft request' (duration: 125.471689ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:14:10.071474Z","caller":"traceutil/trace.go:171","msg":"trace[1004576819] linearizableReadLoop","detail":"{readStateIndex:8577; appliedIndex:8575; }","duration":"144.141696ms","start":"2026-07-15T05:14:09.927306Z","end":"2026-07-15T05:14:10.071448Z","steps":["trace[1004576819] 'read index received' (duration: 77.913956ms)","trace[1004576819] 'applied index is now lower than readState.Index' (duration: 66.22656ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:14:10.071505Z","caller":"traceutil/trace.go:171","msg":"trace[741161452] transaction","detail":"{read_only:false; response_revision:8286; number_of_response:1; }","duration":"316.041758ms","start":"2026-07-15T05:14:09.75543Z","end":"2026-07-15T05:14:10.071472Z","steps":["trace[741161452] 'process raft request' (duration: 249.681324ms)","trace[741161452] 'compare' (duration: 66.15161ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:14:10.071534Z","caller":"traceutil/trace.go:171","msg":"trace[69046977] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"315.436875ms","start":"2026-07-15T05:14:09.756044Z","end":"2026-07-15T05:14:10.071481Z","steps":["trace[69046977] 'process raft request' (duration: 315.347703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:14:10.071762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.441353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-07-15T05:14:10.071779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:14:09.755409Z","time spent":"316.278962ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:14:10.071852Z","caller":"traceutil/trace.go:171","msg":"trace[1181672447] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8287; }","duration":"144.581945ms","start":"2026-07-15T05:14:09.927252Z","end":"2026-07-15T05:14:10.071834Z","steps":["trace[1181672447] 'agreement among raft nodes before linearized reading' (duration: 144.33273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:14:10.072011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:14:09.756026Z","time spent":"315.817182ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:14:10.111006Z","caller":"traceutil/trace.go:171","msg":"trace[1525266494] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"116.585148ms","start":"2026-07-15T05:14:09.994371Z","end":"2026-07-15T05:14:10.110956Z","steps":["trace[1525266494] 'process raft request' (duration: 116.304153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:14:10.111115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.419181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:14:10.111189Z","caller":"traceutil/trace.go:171","msg":"trace[1945590667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8288; }","duration":"145.542144ms","start":"2026-07-15T05:14:09.965626Z","end":"2026-07-15T05:14:10.111168Z","steps":["trace[1945590667] 'agreement among raft nodes before linearized reading' (duration: 145.31763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:14:10.111576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.357108ms","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-07-15T05:14:10.111695Z","caller":"traceutil/trace.go:171","msg":"trace[675364171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8288; }","duration":"173.530681ms","start":"2026-07-15T05:14:09.938137Z","end":"2026-07-15T05:14:10.111667Z","steps":["trace[675364171] 'agreement among raft nodes before linearized reading' (duration: 173.164864ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:14:10.499946Z","caller":"traceutil/trace.go:171","msg":"trace[720806146] transaction","detail":"{read_only:false; response_revision:8292; number_of_response:1; }","duration":"118.466774ms","start":"2026-07-15T05:14:10.381454Z","end":"2026-07-15T05:14:10.499921Z","steps":["trace[720806146] 'process raft request' (duration: 118.411152ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:14:10.499991Z","caller":"traceutil/trace.go:171","msg":"trace[442566218] transaction","detail":"{read_only:false; response_revision:8291; number_of_response:1; }","duration":"126.075ms","start":"2026-07-15T05:14:10.373871Z","end":"2026-07-15T05:14:10.499946Z","steps":["trace[442566218] 'process raft request' (duration: 93.656998ms)","trace[442566218] 'compare' (duration: 32.224388ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:14:18.46451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6167} {"level":"info","ts":"2026-07-15T05:14:18.562181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6167,"took":"95.396421ms","hash":3724119864,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":23535616,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-15T05:14:18.562335Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3724119864,"revision":6167,"compact-revision":3018} {"level":"info","ts":"2026-07-15T05:14:37.964935Z","caller":"traceutil/trace.go:171","msg":"trace[328074135] linearizableReadLoop","detail":"{readStateIndex:8791; appliedIndex:8790; }","duration":"191.070976ms","start":"2026-07-15T05:14:37.773846Z","end":"2026-07-15T05:14:37.964917Z","steps":["trace[328074135] 'read index received' (duration: 190.886032ms)","trace[328074135] 'applied index is now lower than readState.Index' (duration: 184.364µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:14:37.965241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.366661ms","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-07-15T05:14:37.965273Z","caller":"traceutil/trace.go:171","msg":"trace[14708208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8492; }","duration":"191.452813ms","start":"2026-07-15T05:14:37.773812Z","end":"2026-07-15T05:14:37.965265Z","steps":["trace[14708208] 'agreement among raft nodes before linearized reading' (duration: 191.26767ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:14:37.965518Z","caller":"traceutil/trace.go:171","msg":"trace[137468834] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"261.765812ms","start":"2026-07-15T05:14:37.703743Z","end":"2026-07-15T05:14:37.965509Z","steps":["trace[137468834] 'process raft request' (duration: 261.041458ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:14:50.226086Z","caller":"traceutil/trace.go:171","msg":"trace[1884575798] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"148.990999ms","start":"2026-07-15T05:14:50.077057Z","end":"2026-07-15T05:14:50.226048Z","steps":["trace[1884575798] 'process raft request' (duration: 123.140753ms)","trace[1884575798] 'compare' (duration: 25.596421ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:14:50.226118Z","caller":"traceutil/trace.go:171","msg":"trace[2005881535] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"147.924678ms","start":"2026-07-15T05:14:50.078162Z","end":"2026-07-15T05:14:50.226087Z","steps":["trace[2005881535] 'process raft request' (duration: 147.806086ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:14:50.226156Z","caller":"traceutil/trace.go:171","msg":"trace[436803754] linearizableReadLoop","detail":"{readStateIndex:8901; appliedIndex:8898; }","duration":"119.696716ms","start":"2026-07-15T05:14:50.106432Z","end":"2026-07-15T05:14:50.226128Z","steps":["trace[436803754] 'read index received' (duration: 93.874481ms)","trace[436803754] 'applied index is now lower than readState.Index' (duration: 25.821145ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:14:50.226303Z","caller":"traceutil/trace.go:171","msg":"trace[1372993741] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"120.083144ms","start":"2026-07-15T05:14:50.106179Z","end":"2026-07-15T05:14:50.226262Z","steps":["trace[1372993741] 'process raft request' (duration: 119.848229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:14:50.226355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.912541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qj2rz\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-07-15T05:14:50.226394Z","caller":"traceutil/trace.go:171","msg":"trace[1428962352] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qj2rz; range_end:; response_count:1; response_revision:8598; }","duration":"119.962972ms","start":"2026-07-15T05:14:50.106419Z","end":"2026-07-15T05:14:50.226382Z","steps":["trace[1428962352] 'agreement among raft nodes before linearized reading' (duration: 119.804489ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:14:50.879197Z","caller":"traceutil/trace.go:171","msg":"trace[2037694067] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"158.874998ms","start":"2026-07-15T05:14:50.720293Z","end":"2026-07-15T05:14:50.879168Z","steps":["trace[2037694067] 'process raft request' (duration: 158.292437ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:14:50.87945Z","caller":"traceutil/trace.go:171","msg":"trace[1339444003] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"192.414451ms","start":"2026-07-15T05:14:50.686972Z","end":"2026-07-15T05:14:50.879386Z","steps":["trace[1339444003] 'process raft request' (duration: 191.464473ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:15:35.865828Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247b.wal"} {"level":"info","ts":"2026-07-15T05:16:49.320406Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:16:49.325141Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-15T05:16:49.325245Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-15T05:19:18.470936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8342} {"level":"info","ts":"2026-07-15T05:19:18.545553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8342,"took":"72.217317ms","hash":2138129818,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":26632192,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-15T05:19:18.545622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2138129818,"revision":8342,"compact-revision":6167} {"level":"info","ts":"2026-07-15T05:24:18.477807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11015} {"level":"info","ts":"2026-07-15T05:24:18.557293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11015,"took":"76.969847ms","hash":1785647215,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":28356608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-15T05:24:18.557361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1785647215,"revision":11015,"compact-revision":8342} {"level":"warn","ts":"2026-07-15T05:26:11.416239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.577744ms","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-07-15T05:26:11.416378Z","caller":"traceutil/trace.go:171","msg":"trace[1471471337] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:13775; }","duration":"119.739827ms","start":"2026-07-15T05:26:11.296618Z","end":"2026-07-15T05:26:11.416357Z","steps":["trace[1471471337] 'range keys from in-memory index tree' (duration: 119.559564ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:26:13.275573Z","caller":"traceutil/trace.go:171","msg":"trace[1714521505] transaction","detail":"{read_only:false; response_revision:13794; number_of_response:1; }","duration":"107.270447ms","start":"2026-07-15T05:26:13.168271Z","end":"2026-07-15T05:26:13.275541Z","steps":["trace[1714521505] 'process raft request' (duration: 91.721089ms)","trace[1714521505] 'compare' (duration: 15.402906ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:26:33.171415Z","caller":"traceutil/trace.go:171","msg":"trace[1900392242] linearizableReadLoop","detail":"{readStateIndex:14569; appliedIndex:14568; }","duration":"266.320527ms","start":"2026-07-15T05:26:32.905073Z","end":"2026-07-15T05:26:33.171394Z","steps":["trace[1900392242] 'read index received' (duration: 266.202335ms)","trace[1900392242] 'applied index is now lower than readState.Index' (duration: 117.312µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:26:33.171496Z","caller":"traceutil/trace.go:171","msg":"trace[619919434] transaction","detail":"{read_only:false; response_revision:14088; number_of_response:1; }","duration":"302.008521ms","start":"2026-07-15T05:26:32.869456Z","end":"2026-07-15T05:26:33.171464Z","steps":["trace[619919434] 'process raft request' (duration: 301.821578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:26:33.171548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.451049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:26:33.171574Z","caller":"traceutil/trace.go:171","msg":"trace[1541453654] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:14088; }","duration":"266.538571ms","start":"2026-07-15T05:26:32.905027Z","end":"2026-07-15T05:26:33.171566Z","steps":["trace[1541453654] 'agreement among raft nodes before linearized reading' (duration: 266.47254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:26:33.171644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:26:32.869435Z","time spent":"302.130434ms","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:26:33.177375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.956562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-65e30d0b-e5a9-4eb3-b3b0-cb2aa3bad415\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-07-15T05:26:33.177465Z","caller":"traceutil/trace.go:171","msg":"trace[475395079] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-65e30d0b-e5a9-4eb3-b3b0-cb2aa3bad415; range_end:; response_count:1; response_revision:14089; }","duration":"252.094855ms","start":"2026-07-15T05:26:32.925355Z","end":"2026-07-15T05:26:33.177449Z","steps":["trace[475395079] 'agreement among raft nodes before linearized reading' (duration: 251.917732ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:26:33.177407Z","caller":"traceutil/trace.go:171","msg":"trace[893825428] transaction","detail":"{read_only:false; response_revision:14089; number_of_response:1; }","duration":"203.974842ms","start":"2026-07-15T05:26:32.973419Z","end":"2026-07-15T05:26:33.177394Z","steps":["trace[893825428] 'process raft request' (duration: 203.694117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:26:33.177627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.833321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:26:33.177713Z","caller":"traceutil/trace.go:171","msg":"trace[1616410807] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:14089; }","duration":"238.110787ms","start":"2026-07-15T05:26:32.939585Z","end":"2026-07-15T05:26:33.177696Z","steps":["trace[1616410807] 'agreement among raft nodes before linearized reading' (duration: 237.836142ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:27:24.146818Z","caller":"traceutil/trace.go:171","msg":"trace[1224139534] linearizableReadLoop","detail":"{readStateIndex:15214; appliedIndex:15212; }","duration":"139.370212ms","start":"2026-07-15T05:27:24.007418Z","end":"2026-07-15T05:27:24.146788Z","steps":["trace[1224139534] 'read index received' (duration: 5.039917ms)","trace[1224139534] 'applied index is now lower than readState.Index' (duration: 134.329035ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:27:24.146917Z","caller":"traceutil/trace.go:171","msg":"trace[1981971400] transaction","detail":"{read_only:false; response_revision:14718; number_of_response:1; }","duration":"143.373511ms","start":"2026-07-15T05:27:24.003488Z","end":"2026-07-15T05:27:24.146861Z","steps":["trace[1981971400] 'process raft request' (duration: 142.83121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:27:24.14699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.537726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:27:24.147084Z","caller":"traceutil/trace.go:171","msg":"trace[1172228038] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:14718; }","duration":"139.676208ms","start":"2026-07-15T05:27:24.007384Z","end":"2026-07-15T05:27:24.14706Z","steps":["trace[1172228038] 'agreement among raft nodes before linearized reading' (duration: 139.522136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:27:57.642254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.379841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:27:57.642393Z","caller":"traceutil/trace.go:171","msg":"trace[577021548] transaction","detail":"{read_only:false; response_revision:14889; number_of_response:1; }","duration":"177.312049ms","start":"2026-07-15T05:27:57.465058Z","end":"2026-07-15T05:27:57.64237Z","steps":["trace[577021548] 'process raft request' (duration: 60.723654ms)","trace[577021548] 'compare' (duration: 116.274209ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:27:57.900936Z","caller":"traceutil/trace.go:171","msg":"trace[2019295047] linearizableReadLoop","detail":"{readStateIndex:15394; appliedIndex:15393; }","duration":"251.506502ms","start":"2026-07-15T05:27:57.6494Z","end":"2026-07-15T05:27:57.900907Z","steps":["trace[2019295047] 'read index received' (duration: 240.394949ms)","trace[2019295047] 'applied index is now lower than readState.Index' (duration: 11.110413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:27:57.901069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.448244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T05:27:57.901057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.949446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T05:27:57.901101Z","caller":"traceutil/trace.go:171","msg":"trace[1183950246] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14891; }","duration":"284.544696ms","start":"2026-07-15T05:27:57.616547Z","end":"2026-07-15T05:27:57.901092Z","steps":["trace[1183950246] 'agreement among raft nodes before linearized reading' (duration: 284.467894ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:27:57.90114Z","caller":"traceutil/trace.go:171","msg":"trace[1300167744] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14891; }","duration":"257.087059ms","start":"2026-07-15T05:27:57.64403Z","end":"2026-07-15T05:27:57.901117Z","steps":["trace[1300167744] 'agreement among raft nodes before linearized reading' (duration: 256.887925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:27:57.901465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.424633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-15T05:27:57.901476Z","caller":"traceutil/trace.go:171","msg":"trace[1763188974] transaction","detail":"{read_only:false; response_revision:14891; number_of_response:1; }","duration":"278.146362ms","start":"2026-07-15T05:27:57.623283Z","end":"2026-07-15T05:27:57.901429Z","steps":["trace[1763188974] 'process raft request' (duration: 266.56951ms)","trace[1763188974] 'compare' (duration: 10.913679ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:27:57.90152Z","caller":"traceutil/trace.go:171","msg":"trace[882540772] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14891; }","duration":"284.512005ms","start":"2026-07-15T05:27:57.616985Z","end":"2026-07-15T05:27:57.901497Z","steps":["trace[882540772] 'agreement among raft nodes before linearized reading' (duration: 284.375453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:27:57.901859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.344453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-15T05:27:57.901932Z","caller":"traceutil/trace.go:171","msg":"trace[1133367058] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14891; }","duration":"281.463335ms","start":"2026-07-15T05:27:57.620454Z","end":"2026-07-15T05:27:57.901917Z","steps":["trace[1133367058] 'agreement among raft nodes before linearized reading' (duration: 280.494497ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:29:18.487945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13048} {"level":"info","ts":"2026-07-15T05:29:18.561584Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13048,"took":"70.603654ms","hash":766652308,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":31211520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T05:29:18.561659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":766652308,"revision":13048,"compact-revision":11015} {"level":"warn","ts":"2026-07-15T05:30:58.789924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.062095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T05:30:58.789948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.371031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:30:58.790012Z","caller":"traceutil/trace.go:171","msg":"trace[28832113] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:16370; }","duration":"104.171926ms","start":"2026-07-15T05:30:58.685821Z","end":"2026-07-15T05:30:58.789993Z","steps":["trace[28832113] 'agreement among raft nodes before linearized reading' (duration: 104.001143ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:30:58.790028Z","caller":"traceutil/trace.go:171","msg":"trace[701377158] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:16370; }","duration":"104.484963ms","start":"2026-07-15T05:30:58.685524Z","end":"2026-07-15T05:30:58.790009Z","steps":["trace[701377158] 'agreement among raft nodes before linearized reading' (duration: 104.313769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:30:58.790182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.931572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5126"} {"level":"info","ts":"2026-07-15T05:30:58.790219Z","caller":"traceutil/trace.go:171","msg":"trace[2143877894] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:16370; }","duration":"104.998493ms","start":"2026-07-15T05:30:58.685212Z","end":"2026-07-15T05:30:58.79021Z","steps":["trace[2143877894] 'agreement among raft nodes before linearized reading' (duration: 104.938112ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:33:46.107383Z","caller":"traceutil/trace.go:171","msg":"trace[1094065063] transaction","detail":"{read_only:false; response_revision:17481; number_of_response:1; }","duration":"211.56112ms","start":"2026-07-15T05:33:45.895788Z","end":"2026-07-15T05:33:46.107349Z","steps":["trace[1094065063] 'process raft request' (duration: 187.512638ms)","trace[1094065063] 'compare' (duration: 23.842608ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:33:53.545918Z","caller":"traceutil/trace.go:171","msg":"trace[789074204] transaction","detail":"{read_only:false; response_revision:17518; number_of_response:1; }","duration":"268.516362ms","start":"2026-07-15T05:33:53.277374Z","end":"2026-07-15T05:33:53.545891Z","steps":["trace[789074204] 'process raft request' (duration: 268.339919ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:34:18.494361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15435} {"level":"info","ts":"2026-07-15T05:34:18.586987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15435,"took":"89.11135ms","hash":3459070082,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":32534528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T05:34:18.587047Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3459070082,"revision":15435,"compact-revision":13048} {"level":"info","ts":"2026-07-15T05:34:38.134369Z","caller":"traceutil/trace.go:171","msg":"trace[1507751599] linearizableReadLoop","detail":"{readStateIndex:18733; appliedIndex:18730; }","duration":"108.232386ms","start":"2026-07-15T05:34:38.026116Z","end":"2026-07-15T05:34:38.134348Z","steps":["trace[1507751599] 'read index received' (duration: 1.515679ms)","trace[1507751599] 'applied index is now lower than readState.Index' (duration: 106.715567ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:34:38.134471Z","caller":"traceutil/trace.go:171","msg":"trace[1130390106] transaction","detail":"{read_only:false; response_revision:18126; number_of_response:1; }","duration":"124.04656ms","start":"2026-07-15T05:34:38.010368Z","end":"2026-07-15T05:34:38.134415Z","steps":["trace[1130390106] 'process raft request' (duration: 107.489453ms)","trace[1130390106] 'compare' (duration: 16.372864ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:34:38.134664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.536053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-db-sync-k6j2m\" ","response":"range_response_count:1 size:5268"} {"level":"info","ts":"2026-07-15T05:34:38.134761Z","caller":"traceutil/trace.go:171","msg":"trace[1303046157] range","detail":"{range_begin:/registry/pods/openstack/heat-db-sync-k6j2m; range_end:; response_count:1; response_revision:18126; }","duration":"108.684415ms","start":"2026-07-15T05:34:38.026062Z","end":"2026-07-15T05:34:38.134747Z","steps":["trace[1303046157] 'agreement among raft nodes before linearized reading' (duration: 108.376919ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:37:34.297492Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:37:34.301693Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-15T05:37:34.301777Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-15T05:38:12.641896Z","caller":"traceutil/trace.go:171","msg":"trace[1737629522] transaction","detail":"{read_only:false; response_revision:19723; number_of_response:1; }","duration":"191.193008ms","start":"2026-07-15T05:38:12.450681Z","end":"2026-07-15T05:38:12.641874Z","steps":["trace[1737629522] 'process raft request' (duration: 189.138629ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:12.642479Z","caller":"traceutil/trace.go:171","msg":"trace[956252239] transaction","detail":"{read_only:false; response_revision:19724; number_of_response:1; }","duration":"191.536025ms","start":"2026-07-15T05:38:12.450908Z","end":"2026-07-15T05:38:12.642444Z","steps":["trace[956252239] 'process raft request' (duration: 191.230709ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:12.642561Z","caller":"traceutil/trace.go:171","msg":"trace[1312131137] transaction","detail":"{read_only:false; response_revision:19726; number_of_response:1; }","duration":"190.975044ms","start":"2026-07-15T05:38:12.451571Z","end":"2026-07-15T05:38:12.642546Z","steps":["trace[1312131137] 'process raft request' (duration: 190.843332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:38:12.642582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.47149ms","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-07-15T05:38:12.642631Z","caller":"traceutil/trace.go:171","msg":"trace[811686036] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19726; }","duration":"171.556142ms","start":"2026-07-15T05:38:12.471065Z","end":"2026-07-15T05:38:12.642621Z","steps":["trace[811686036] 'agreement among raft nodes before linearized reading' (duration: 171.47307ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:12.642488Z","caller":"traceutil/trace.go:171","msg":"trace[86242480] linearizableReadLoop","detail":"{readStateIndex:20386; appliedIndex:20382; }","duration":"171.367118ms","start":"2026-07-15T05:38:12.471092Z","end":"2026-07-15T05:38:12.642459Z","steps":["trace[86242480] 'read index received' (duration: 168.657066ms)","trace[86242480] 'applied index is now lower than readState.Index' (duration: 2.707132ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:38:12.642483Z","caller":"traceutil/trace.go:171","msg":"trace[556127995] transaction","detail":"{read_only:false; response_revision:19725; number_of_response:1; }","duration":"191.492124ms","start":"2026-07-15T05:38:12.450964Z","end":"2026-07-15T05:38:12.642456Z","steps":["trace[556127995] 'process raft request' (duration: 191.365112ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:18.500783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17811} {"level":"info","ts":"2026-07-15T05:39:18.596367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17811,"took":"92.355782ms","hash":3596067704,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":33710080,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-15T05:39:18.596427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3596067704,"revision":17811,"compact-revision":15435} {"level":"info","ts":"2026-07-15T05:39:40.840825Z","caller":"traceutil/trace.go:171","msg":"trace[626940686] transaction","detail":"{read_only:false; response_revision:20211; number_of_response:1; }","duration":"111.002299ms","start":"2026-07-15T05:39:40.729793Z","end":"2026-07-15T05:39:40.840796Z","steps":["trace[626940686] 'process raft request' (duration: 110.853046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:39:41.450311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.303652ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:39:41.450525Z","caller":"traceutil/trace.go:171","msg":"trace[1889981786] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:20212; }","duration":"153.533007ms","start":"2026-07-15T05:39:41.296954Z","end":"2026-07-15T05:39:41.450487Z","steps":["trace[1889981786] 'range keys from in-memory index tree' (duration: 153.279141ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:41.876869Z","caller":"traceutil/trace.go:171","msg":"trace[462032773] linearizableReadLoop","detail":"{readStateIndex:20895; appliedIndex:20894; }","duration":"200.319604ms","start":"2026-07-15T05:39:41.676526Z","end":"2026-07-15T05:39:41.876845Z","steps":["trace[462032773] 'read index received' (duration: 106.186188ms)","trace[462032773] 'applied index is now lower than readState.Index' (duration: 94.132336ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:39:41.876914Z","caller":"traceutil/trace.go:171","msg":"trace[105653709] transaction","detail":"{read_only:false; response_revision:20214; number_of_response:1; }","duration":"273.919365ms","start":"2026-07-15T05:39:41.602949Z","end":"2026-07-15T05:39:41.876868Z","steps":["trace[105653709] 'process raft request' (duration: 179.85203ms)","trace[105653709] 'compare' (duration: 93.950773ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:39:41.877164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.099963ms","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-07-15T05:39:41.87716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.621429ms","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-07-15T05:39:41.877234Z","caller":"traceutil/trace.go:171","msg":"trace[382959743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20214; }","duration":"196.226026ms","start":"2026-07-15T05:39:41.680996Z","end":"2026-07-15T05:39:41.877222Z","steps":["trace[382959743] 'agreement among raft nodes before linearized reading' (duration: 196.023712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:39:41.877232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.417279ms","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-07-15T05:39:41.877249Z","caller":"traceutil/trace.go:171","msg":"trace[803625959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20214; }","duration":"200.718231ms","start":"2026-07-15T05:39:41.676512Z","end":"2026-07-15T05:39:41.87723Z","steps":["trace[803625959] 'agreement among raft nodes before linearized reading' (duration: 200.423555ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:41.87727Z","caller":"traceutil/trace.go:171","msg":"trace[1252262578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20214; }","duration":"195.49676ms","start":"2026-07-15T05:39:41.681765Z","end":"2026-07-15T05:39:41.877262Z","steps":["trace[1252262578] 'agreement among raft nodes before linearized reading' (duration: 195.373328ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:42.098225Z","caller":"traceutil/trace.go:171","msg":"trace[1152674180] transaction","detail":"{read_only:false; response_revision:20215; number_of_response:1; }","duration":"216.323351ms","start":"2026-07-15T05:39:41.881876Z","end":"2026-07-15T05:39:42.0982Z","steps":["trace[1152674180] 'process raft request' (duration: 196.532761ms)","trace[1152674180] 'compare' (duration: 19.683948ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:39:42.0992Z","caller":"traceutil/trace.go:171","msg":"trace[1152298666] transaction","detail":"{read_only:false; response_revision:20216; number_of_response:1; }","duration":"217.257809ms","start":"2026-07-15T05:39:41.881916Z","end":"2026-07-15T05:39:42.099174Z","steps":["trace[1152298666] 'process raft request' (duration: 217.031555ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:42.099422Z","caller":"traceutil/trace.go:171","msg":"trace[1321481614] transaction","detail":"{read_only:false; response_revision:20217; number_of_response:1; }","duration":"217.206418ms","start":"2026-07-15T05:39:41.882194Z","end":"2026-07-15T05:39:42.0994Z","steps":["trace[1321481614] 'process raft request' (duration: 216.937773ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:42.099846Z","caller":"traceutil/trace.go:171","msg":"trace[662848607] transaction","detail":"{read_only:false; response_revision:20218; number_of_response:1; }","duration":"217.397241ms","start":"2026-07-15T05:39:41.882423Z","end":"2026-07-15T05:39:42.09982Z","steps":["trace[662848607] 'process raft request' (duration: 216.915362ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:42.10005Z","caller":"traceutil/trace.go:171","msg":"trace[1882377980] linearizableReadLoop","detail":"{readStateIndex:20899; appliedIndex:20895; }","duration":"217.046094ms","start":"2026-07-15T05:39:41.882979Z","end":"2026-07-15T05:39:42.100025Z","steps":["trace[1882377980] 'read index received' (duration: 195.530391ms)","trace[1882377980] 'applied index is now lower than readState.Index' (duration: 21.513453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:39:42.100259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.261308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:39:42.100383Z","caller":"traceutil/trace.go:171","msg":"trace[1873563720] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:20220; }","duration":"217.414901ms","start":"2026-07-15T05:39:41.882953Z","end":"2026-07-15T05:39:42.100368Z","steps":["trace[1873563720] 'agreement among raft nodes before linearized reading' (duration: 217.163356ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:43:33.445081Z","caller":"traceutil/trace.go:171","msg":"trace[355074323] linearizableReadLoop","detail":"{readStateIndex:23405; appliedIndex:23404; }","duration":"171.565362ms","start":"2026-07-15T05:43:33.273486Z","end":"2026-07-15T05:43:33.445051Z","steps":["trace[355074323] 'read index received' (duration: 171.358468ms)","trace[355074323] 'applied index is now lower than readState.Index' (duration: 205.444µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:43:33.445255Z","caller":"traceutil/trace.go:171","msg":"trace[2144416142] transaction","detail":"{read_only:false; response_revision:22654; number_of_response:1; }","duration":"195.086514ms","start":"2026-07-15T05:43:33.250156Z","end":"2026-07-15T05:43:33.445243Z","steps":["trace[2144416142] 'process raft request' (duration: 194.706087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:33.445556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.431953ms","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-07-15T05:43:33.445644Z","caller":"traceutil/trace.go:171","msg":"trace[1518528750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22654; }","duration":"167.557625ms","start":"2026-07-15T05:43:33.278065Z","end":"2026-07-15T05:43:33.445622Z","steps":["trace[1518528750] 'agreement among raft nodes before linearized reading' (duration: 167.341951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:33.445843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.336186ms","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-07-15T05:43:33.445884Z","caller":"traceutil/trace.go:171","msg":"trace[1125034544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22654; }","duration":"172.424218ms","start":"2026-07-15T05:43:33.273443Z","end":"2026-07-15T05:43:33.445868Z","steps":["trace[1125034544] 'agreement among raft nodes before linearized reading' (duration: 172.286075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:43:33.445884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.077595ms","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-07-15T05:43:33.445918Z","caller":"traceutil/trace.go:171","msg":"trace[1419354139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22654; }","duration":"167.121246ms","start":"2026-07-15T05:43:33.278788Z","end":"2026-07-15T05:43:33.445909Z","steps":["trace[1419354139] 'agreement among raft nodes before linearized reading' (duration: 167.014154ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:18.506924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20096} {"level":"info","ts":"2026-07-15T05:44:18.613287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20096,"took":"102.553198ms","hash":2076429651,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":43073536,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-15T05:44:18.613364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2076429651,"revision":20096,"compact-revision":17811} {"level":"info","ts":"2026-07-15T05:46:40.707504Z","caller":"traceutil/trace.go:171","msg":"trace[1704273699] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"306.213936ms","start":"2026-07-15T05:46:40.401276Z","end":"2026-07-15T05:46:40.70749Z","steps":["trace[1704273699] 'process raft request' (duration: 306.071943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:40.707616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:40.401257Z","time spent":"306.304748ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:40.707689Z","caller":"traceutil/trace.go:171","msg":"trace[625029241] linearizableReadLoop","detail":"{readStateIndex:25431; appliedIndex:25429; }","duration":"285.450618ms","start":"2026-07-15T05:46:40.422004Z","end":"2026-07-15T05:46:40.707454Z","steps":["trace[625029241] 'read index received' (duration: 41.256622ms)","trace[625029241] 'applied index is now lower than readState.Index' (duration: 244.191546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:40.707783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.780444ms","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-07-15T05:46:40.707804Z","caller":"traceutil/trace.go:171","msg":"trace[2056372371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24631; }","duration":"285.829775ms","start":"2026-07-15T05:46:40.421968Z","end":"2026-07-15T05:46:40.707798Z","steps":["trace[2056372371] 'agreement among raft nodes before linearized reading' (duration: 285.738424ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:40.795691Z","caller":"traceutil/trace.go:171","msg":"trace[877544846] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"136.56316ms","start":"2026-07-15T05:46:40.659108Z","end":"2026-07-15T05:46:40.795671Z","steps":["trace[877544846] 'process raft request' (duration: 136.449758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:40.984614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.737252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:46:40.985358Z","caller":"traceutil/trace.go:171","msg":"trace[1976226326] linearizableReadLoop","detail":"{readStateIndex:25434; appliedIndex:25432; }","duration":"100.220274ms","start":"2026-07-15T05:46:40.885115Z","end":"2026-07-15T05:46:40.985335Z","steps":["trace[1976226326] 'read index received' (duration: 32.651µs)","trace[1976226326] 'applied index is now lower than readState.Index' (duration: 100.185563ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:40.985511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.385757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:40.9856Z","caller":"traceutil/trace.go:171","msg":"trace[1457698804] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:24634; }","duration":"100.480939ms","start":"2026-07-15T05:46:40.885083Z","end":"2026-07-15T05:46:40.985564Z","steps":["trace[1457698804] 'agreement among raft nodes before linearized reading' (duration: 100.322206ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:40.985599Z","caller":"traceutil/trace.go:171","msg":"trace[1037917239] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"274.198722ms","start":"2026-07-15T05:46:40.711377Z","end":"2026-07-15T05:46:40.985575Z","steps":["trace[1037917239] 'process raft request' (duration: 133.39444ms)","trace[1037917239] 'compare' (duration: 139.466847ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:40.985506Z","caller":"traceutil/trace.go:171","msg":"trace[854608425] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"216.894972ms","start":"2026-07-15T05:46:40.768583Z","end":"2026-07-15T05:46:40.985478Z","steps":["trace[854608425] 'process raft request' (duration: 216.125708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:45.363157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.505934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:46:45.363298Z","caller":"traceutil/trace.go:171","msg":"trace[1517495546] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"339.143978ms","start":"2026-07-15T05:46:45.024127Z","end":"2026-07-15T05:46:45.363271Z","steps":["trace[1517495546] 'process raft request' (duration: 219.39481ms)","trace[1517495546] 'compare' (duration: 119.228718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:45.363406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:45.024096Z","time spent":"339.260741ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:45.365303Z","caller":"traceutil/trace.go:171","msg":"trace[124840055] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"140.453137ms","start":"2026-07-15T05:46:45.224823Z","end":"2026-07-15T05:46:45.365276Z","steps":["trace[124840055] 'process raft request' (duration: 140.395756ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:45.36535Z","caller":"traceutil/trace.go:171","msg":"trace[1776689417] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"273.832346ms","start":"2026-07-15T05:46:45.091485Z","end":"2026-07-15T05:46:45.365318Z","steps":["trace[1776689417] 'process raft request' (duration: 273.583061ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:45.828901Z","caller":"traceutil/trace.go:171","msg":"trace[1691612254] linearizableReadLoop","detail":"{readStateIndex:25473; appliedIndex:25472; }","duration":"135.172264ms","start":"2026-07-15T05:46:45.693696Z","end":"2026-07-15T05:46:45.828868Z","steps":["trace[1691612254] 'read index received' (duration: 116.708571ms)","trace[1691612254] 'applied index is now lower than readState.Index' (duration: 18.462033ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:45.828963Z","caller":"traceutil/trace.go:171","msg":"trace[1952621035] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"140.767252ms","start":"2026-07-15T05:46:45.688164Z","end":"2026-07-15T05:46:45.828931Z","steps":["trace[1952621035] 'process raft request' (duration: 122.322658ms)","trace[1952621035] 'compare' (duration: 18.24155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:45.829155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.431649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-15T05:46:45.829275Z","caller":"traceutil/trace.go:171","msg":"trace[1535175765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24672; }","duration":"135.595032ms","start":"2026-07-15T05:46:45.693653Z","end":"2026-07-15T05:46:45.829248Z","steps":["trace[1535175765] 'agreement among raft nodes before linearized reading' (duration: 135.316626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:46.085872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.137977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:46.085987Z","caller":"traceutil/trace.go:171","msg":"trace[1046377437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24673; }","duration":"119.30102ms","start":"2026-07-15T05:46:45.966667Z","end":"2026-07-15T05:46:46.085968Z","steps":["trace[1046377437] 'range keys from in-memory index tree' (duration: 119.054295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:46.952165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.515491ms","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-07-15T05:46:46.952234Z","caller":"traceutil/trace.go:171","msg":"trace[444417604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24677; }","duration":"221.623193ms","start":"2026-07-15T05:46:46.730596Z","end":"2026-07-15T05:46:46.952219Z","steps":["trace[444417604] 'range keys from in-memory index tree' (duration: 221.354628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:47.101315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.192191ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-15T05:46:47.101585Z","caller":"traceutil/trace.go:171","msg":"trace[993397303] linearizableReadLoop","detail":"{readStateIndex:25479; appliedIndex:25478; }","duration":"316.449143ms","start":"2026-07-15T05:46:46.785098Z","end":"2026-07-15T05:46:47.101547Z","steps":["trace[993397303] 'read index received' (duration: 41.021127ms)","trace[993397303] 'applied index is now lower than readState.Index' (duration: 275.420716ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:47.101838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.710379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:46:47.101887Z","caller":"traceutil/trace.go:171","msg":"trace[1643585723] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:24677; }","duration":"316.825661ms","start":"2026-07-15T05:46:46.785048Z","end":"2026-07-15T05:46:47.101874Z","steps":["trace[1643585723] 'agreement among raft nodes before linearized reading' (duration: 316.674977ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:47.101856Z","caller":"traceutil/trace.go:171","msg":"trace[178091516] transaction","detail":"{read_only:false; response_revision:24678; number_of_response:1; }","duration":"288.314843ms","start":"2026-07-15T05:46:46.813515Z","end":"2026-07-15T05:46:47.10183Z","steps":["trace[178091516] 'process raft request' (duration: 287.936345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:47.101942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:46.785026Z","time spent":"316.904203ms","remote":"127.0.0.1:48360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":20,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:46:47.102634Z","caller":"traceutil/trace.go:171","msg":"trace[2041123925] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"145.944121ms","start":"2026-07-15T05:46:46.956674Z","end":"2026-07-15T05:46:47.102618Z","steps":["trace[2041123925] 'process raft request' (duration: 145.89654ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:47.102878Z","caller":"traceutil/trace.go:171","msg":"trace[295112747] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"203.684229ms","start":"2026-07-15T05:46:46.899153Z","end":"2026-07-15T05:46:47.102837Z","steps":["trace[295112747] 'process raft request' (duration: 203.322632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:47.104231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.135768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:46:47.104317Z","caller":"traceutil/trace.go:171","msg":"trace[969947816] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24681; }","duration":"252.287832ms","start":"2026-07-15T05:46:46.852Z","end":"2026-07-15T05:46:47.104288Z","steps":["trace[969947816] 'agreement among raft nodes before linearized reading' (duration: 252.101378ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:47.478316Z","caller":"traceutil/trace.go:171","msg":"trace[959774636] linearizableReadLoop","detail":"{readStateIndex:25484; appliedIndex:25483; }","duration":"109.180115ms","start":"2026-07-15T05:46:47.369116Z","end":"2026-07-15T05:46:47.478296Z","steps":["trace[959774636] 'read index received' (duration: 108.982631ms)","trace[959774636] 'applied index is now lower than readState.Index' (duration: 196.554µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:47.478545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.41096ms","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-07-15T05:46:47.478576Z","caller":"traceutil/trace.go:171","msg":"trace[1946138325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24682; }","duration":"109.481201ms","start":"2026-07-15T05:46:47.369087Z","end":"2026-07-15T05:46:47.478569Z","steps":["trace[1946138325] 'agreement among raft nodes before linearized reading' (duration: 109.279857ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:47.47886Z","caller":"traceutil/trace.go:171","msg":"trace[429769635] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"110.242885ms","start":"2026-07-15T05:46:47.368608Z","end":"2026-07-15T05:46:47.478851Z","steps":["trace[429769635] 'process raft request' (duration: 109.524442ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:47.64455Z","caller":"traceutil/trace.go:171","msg":"trace[314379685] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"160.087511ms","start":"2026-07-15T05:46:47.484435Z","end":"2026-07-15T05:46:47.644522Z","steps":["trace[314379685] 'process raft request' (duration: 160.01258ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:47.64504Z","caller":"traceutil/trace.go:171","msg":"trace[1146452864] transaction","detail":"{read_only:false; response_revision:24683; number_of_response:1; }","duration":"275.822843ms","start":"2026-07-15T05:46:47.369198Z","end":"2026-07-15T05:46:47.645021Z","steps":["trace[1146452864] 'process raft request' (duration: 246.769605ms)","trace[1146452864] 'compare' (duration: 28.052989ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:47.789032Z","caller":"traceutil/trace.go:171","msg":"trace[1637826813] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"111.646792ms","start":"2026-07-15T05:46:47.677352Z","end":"2026-07-15T05:46:47.788999Z","steps":["trace[1637826813] 'process raft request' (duration: 100.188643ms)","trace[1637826813] 'compare' (duration: 11.056182ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:47.789083Z","caller":"traceutil/trace.go:171","msg":"trace[1899039290] linearizableReadLoop","detail":"{readStateIndex:25487; appliedIndex:25486; }","duration":"100.178053ms","start":"2026-07-15T05:46:47.688742Z","end":"2026-07-15T05:46:47.78892Z","steps":["trace[1899039290] 'read index received' (duration: 88.840355ms)","trace[1899039290] 'applied index is now lower than readState.Index' (duration: 11.336408ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:47.789191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.431067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:47.789216Z","caller":"traceutil/trace.go:171","msg":"trace[2050789600] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24685; }","duration":"100.509229ms","start":"2026-07-15T05:46:47.688699Z","end":"2026-07-15T05:46:47.789208Z","steps":["trace[2050789600] 'agreement among raft nodes before linearized reading' (duration: 100.435918ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:47.944437Z","caller":"traceutil/trace.go:171","msg":"trace[1967161013] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"110.45706ms","start":"2026-07-15T05:46:47.833952Z","end":"2026-07-15T05:46:47.944409Z","steps":["trace[1967161013] 'process raft request' (duration: 97.580203ms)","trace[1967161013] 'compare' (duration: 12.632612ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:48.258217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.512516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:46:48.258342Z","caller":"traceutil/trace.go:171","msg":"trace[1590880419] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"307.551202ms","start":"2026-07-15T05:46:47.950763Z","end":"2026-07-15T05:46:48.258314Z","steps":["trace[1590880419] 'process raft request' (duration: 142.861392ms)","trace[1590880419] 'compare' (duration: 164.388474ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:48.258435Z","caller":"traceutil/trace.go:171","msg":"trace[1212397781] transaction","detail":"{read_only:false; response_revision:24688; number_of_response:1; }","duration":"101.296244ms","start":"2026-07-15T05:46:48.157118Z","end":"2026-07-15T05:46:48.258414Z","steps":["trace[1212397781] 'process raft request' (duration: 101.211552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:48.258464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:47.950743Z","time spent":"307.657353ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:48.258581Z","caller":"traceutil/trace.go:171","msg":"trace[322161223] linearizableReadLoop","detail":"{readStateIndex:25489; appliedIndex:25488; }","duration":"292.383141ms","start":"2026-07-15T05:46:47.966189Z","end":"2026-07-15T05:46:48.258572Z","steps":["trace[322161223] 'read index received' (duration: 127.506457ms)","trace[322161223] 'applied index is now lower than readState.Index' (duration: 164.873244ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:48.258737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.543914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:48.258773Z","caller":"traceutil/trace.go:171","msg":"trace[369270435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24688; }","duration":"292.580314ms","start":"2026-07-15T05:46:47.966184Z","end":"2026-07-15T05:46:48.258764Z","steps":["trace[369270435] 'agreement among raft nodes before linearized reading' (duration: 292.522893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:48.258909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.884992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-07-15T05:46:48.25897Z","caller":"traceutil/trace.go:171","msg":"trace[86824138] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:24688; }","duration":"218.980314ms","start":"2026-07-15T05:46:48.039974Z","end":"2026-07-15T05:46:48.258955Z","steps":["trace[86824138] 'agreement among raft nodes before linearized reading' (duration: 218.784379ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:48.495417Z","caller":"traceutil/trace.go:171","msg":"trace[126231198] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"146.760906ms","start":"2026-07-15T05:46:48.348618Z","end":"2026-07-15T05:46:48.495379Z","steps":["trace[126231198] 'process raft request' (duration: 146.252237ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:58.988876Z","caller":"traceutil/trace.go:171","msg":"trace[1462543982] linearizableReadLoop","detail":"{readStateIndex:25628; appliedIndex:25627; }","duration":"145.414731ms","start":"2026-07-15T05:46:58.843438Z","end":"2026-07-15T05:46:58.988852Z","steps":["trace[1462543982] 'read index received' (duration: 143.169977ms)","trace[1462543982] 'applied index is now lower than readState.Index' (duration: 2.243444ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:58.988921Z","caller":"traceutil/trace.go:171","msg":"trace[818630088] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"155.21861ms","start":"2026-07-15T05:46:58.833677Z","end":"2026-07-15T05:46:58.988895Z","steps":["trace[818630088] 'process raft request' (duration: 152.987587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:58.98905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.585464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:46:58.989133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.691494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23da8ede-db65-49f6-b7d7-ef7bf60e6d2d\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-15T05:46:58.989152Z","caller":"traceutil/trace.go:171","msg":"trace[1575972153] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:24824; }","duration":"145.732227ms","start":"2026-07-15T05:46:58.843401Z","end":"2026-07-15T05:46:58.989133Z","steps":["trace[1575972153] 'agreement among raft nodes before linearized reading' (duration: 145.568684ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:58.989194Z","caller":"traceutil/trace.go:171","msg":"trace[1869167851] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23da8ede-db65-49f6-b7d7-ef7bf60e6d2d; range_end:; response_count:1; response_revision:24824; }","duration":"111.778526ms","start":"2026-07-15T05:46:58.877384Z","end":"2026-07-15T05:46:58.989163Z","steps":["trace[1869167851] 'agreement among raft nodes before linearized reading' (duration: 111.591222ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:59.29286Z","caller":"traceutil/trace.go:171","msg":"trace[1014699490] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"296.970089ms","start":"2026-07-15T05:46:58.995705Z","end":"2026-07-15T05:46:59.292675Z","steps":["trace[1014699490] 'process raft request' (duration: 273.04766ms)","trace[1014699490] 'compare' (duration: 23.788546ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:59.294111Z","caller":"traceutil/trace.go:171","msg":"trace[1049446081] linearizableReadLoop","detail":"{readStateIndex:25631; appliedIndex:25628; }","duration":"136.658532ms","start":"2026-07-15T05:46:59.157427Z","end":"2026-07-15T05:46:59.294085Z","steps":["trace[1049446081] 'read index received' (duration: 111.336406ms)","trace[1049446081] 'applied index is now lower than readState.Index' (duration: 25.321176ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:59.294253Z","caller":"traceutil/trace.go:171","msg":"trace[566822933] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"149.021729ms","start":"2026-07-15T05:46:59.145203Z","end":"2026-07-15T05:46:59.294225Z","steps":["trace[566822933] 'process raft request' (duration: 148.838345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:59.294518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.07435ms","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-07-15T05:46:59.294558Z","caller":"traceutil/trace.go:171","msg":"trace[1658070561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24827; }","duration":"137.162392ms","start":"2026-07-15T05:46:59.157385Z","end":"2026-07-15T05:46:59.294547Z","steps":["trace[1658070561] 'agreement among raft nodes before linearized reading' (duration: 137.011599ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:59.294446Z","caller":"traceutil/trace.go:171","msg":"trace[2144268806] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"152.453306ms","start":"2026-07-15T05:46:59.141925Z","end":"2026-07-15T05:46:59.294378Z","steps":["trace[2144268806] 'process raft request' (duration: 151.983777ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:59.580982Z","caller":"traceutil/trace.go:171","msg":"trace[1656824893] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"280.32634ms","start":"2026-07-15T05:46:59.300601Z","end":"2026-07-15T05:46:59.580927Z","steps":["trace[1656824893] 'process raft request' (duration: 205.931733ms)","trace[1656824893] 'compare' (duration: 74.160902ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:59.780929Z","caller":"traceutil/trace.go:171","msg":"trace[1876599323] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"223.606771ms","start":"2026-07-15T05:46:59.557284Z","end":"2026-07-15T05:46:59.780891Z","steps":["trace[1876599323] 'process raft request' (duration: 222.849666ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:59.9334Z","caller":"traceutil/trace.go:171","msg":"trace[645117470] linearizableReadLoop","detail":"{readStateIndex:25638; appliedIndex:25637; }","duration":"106.069386ms","start":"2026-07-15T05:46:59.827306Z","end":"2026-07-15T05:46:59.933376Z","steps":["trace[645117470] 'read index received' (duration: 104.980536ms)","trace[645117470] 'applied index is now lower than readState.Index' (duration: 1.08742ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:59.933609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.28166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23da8ede-db65-49f6-b7d7-ef7bf60e6d2d\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-07-15T05:46:59.933551Z","caller":"traceutil/trace.go:171","msg":"trace[1152376582] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"107.211677ms","start":"2026-07-15T05:46:59.82629Z","end":"2026-07-15T05:46:59.933501Z","steps":["trace[1152376582] 'process raft request' (duration: 106.067656ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:59.93366Z","caller":"traceutil/trace.go:171","msg":"trace[1541281088] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23da8ede-db65-49f6-b7d7-ef7bf60e6d2d; range_end:; response_count:1; response_revision:24834; }","duration":"106.377231ms","start":"2026-07-15T05:46:59.827267Z","end":"2026-07-15T05:46:59.933644Z","steps":["trace[1541281088] 'agreement among raft nodes before linearized reading' (duration: 106.198398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:00.36428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.673963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:00.364406Z","caller":"traceutil/trace.go:171","msg":"trace[39206677] linearizableReadLoop","detail":"{readStateIndex:25639; appliedIndex:25638; }","duration":"105.451434ms","start":"2026-07-15T05:47:00.258937Z","end":"2026-07-15T05:47:00.364389Z","steps":["trace[39206677] 'read index received' (duration: 60.211µs)","trace[39206677] 'applied index is now lower than readState.Index' (duration: 105.389653ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:00.364508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.570847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:00.364542Z","caller":"traceutil/trace.go:171","msg":"trace[397844542] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24835; }","duration":"105.636267ms","start":"2026-07-15T05:47:00.258892Z","end":"2026-07-15T05:47:00.364529Z","steps":["trace[397844542] 'agreement among raft nodes before linearized reading' (duration: 105.567106ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:00.364489Z","caller":"traceutil/trace.go:171","msg":"trace[1969910288] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"376.951044ms","start":"2026-07-15T05:46:59.987505Z","end":"2026-07-15T05:47:00.364456Z","steps":["trace[1969910288] 'process raft request' (duration: 141.006736ms)","trace[1969910288] 'compare' (duration: 235.50004ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:00.364906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:59.987486Z","time spent":"377.335312ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:00.522091Z","caller":"traceutil/trace.go:171","msg":"trace[1008722465] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"127.295082ms","start":"2026-07-15T05:47:00.394764Z","end":"2026-07-15T05:47:00.522059Z","steps":["trace[1008722465] 'process raft request' (duration: 116.418533ms)","trace[1008722465] 'compare' (duration: 10.657845ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:00.654964Z","caller":"traceutil/trace.go:171","msg":"trace[1420856751] linearizableReadLoop","detail":"{readStateIndex:25642; appliedIndex:25641; }","duration":"100.05622ms","start":"2026-07-15T05:47:00.554882Z","end":"2026-07-15T05:47:00.654938Z","steps":["trace[1420856751] 'read index received' (duration: 98.632642ms)","trace[1420856751] 'applied index is now lower than readState.Index' (duration: 1.422348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:00.655155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.258825ms","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-07-15T05:47:00.65514Z","caller":"traceutil/trace.go:171","msg":"trace[1373381643] transaction","detail":"{read_only:false; response_revision:24838; number_of_response:1; }","duration":"108.780148ms","start":"2026-07-15T05:47:00.546327Z","end":"2026-07-15T05:47:00.655107Z","steps":["trace[1373381643] 'process raft request' (duration: 107.122636ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:00.655197Z","caller":"traceutil/trace.go:171","msg":"trace[1061911942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24838; }","duration":"100.358897ms","start":"2026-07-15T05:47:00.554825Z","end":"2026-07-15T05:47:00.655183Z","steps":["trace[1061911942] 'agreement among raft nodes before linearized reading' (duration: 100.206554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:00.92965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.731891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:00.929879Z","caller":"traceutil/trace.go:171","msg":"trace[779498473] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"270.098304ms","start":"2026-07-15T05:47:00.659733Z","end":"2026-07-15T05:47:00.929831Z","steps":["trace[779498473] 'process raft request' (duration: 141.051808ms)","trace[779498473] 'compare' (duration: 128.596958ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:00.929928Z","caller":"traceutil/trace.go:171","msg":"trace[661422413] linearizableReadLoop","detail":"{readStateIndex:25643; appliedIndex:25642; }","duration":"248.803015ms","start":"2026-07-15T05:47:00.681104Z","end":"2026-07-15T05:47:00.929907Z","steps":["trace[661422413] 'read index received' (duration: 119.685557ms)","trace[661422413] 'applied index is now lower than readState.Index' (duration: 129.115138ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:00.930141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.000688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-15T05:47:00.930172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.326693ms","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-07-15T05:47:00.930247Z","caller":"traceutil/trace.go:171","msg":"trace[830616447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24839; }","duration":"103.434606ms","start":"2026-07-15T05:47:00.826792Z","end":"2026-07-15T05:47:00.930227Z","steps":["trace[830616447] 'agreement among raft nodes before linearized reading' (duration: 103.229552ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:00.930241Z","caller":"traceutil/trace.go:171","msg":"trace[1814274583] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24839; }","duration":"249.145631ms","start":"2026-07-15T05:47:00.681075Z","end":"2026-07-15T05:47:00.930221Z","steps":["trace[1814274583] 'agreement among raft nodes before linearized reading' (duration: 248.896407ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:01.504924Z","caller":"traceutil/trace.go:171","msg":"trace[2135570106] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"204.670658ms","start":"2026-07-15T05:47:01.30023Z","end":"2026-07-15T05:47:01.5049Z","steps":["trace[2135570106] 'process raft request' (duration: 204.496385ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:01.536109Z","caller":"traceutil/trace.go:171","msg":"trace[1627593030] linearizableReadLoop","detail":"{readStateIndex:25646; appliedIndex:25644; }","duration":"198.271506ms","start":"2026-07-15T05:47:01.337819Z","end":"2026-07-15T05:47:01.536091Z","steps":["trace[1627593030] 'read index received' (duration: 166.899434ms)","trace[1627593030] 'applied index is now lower than readState.Index' (duration: 31.370632ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:01.536194Z","caller":"traceutil/trace.go:171","msg":"trace[746613166] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"235.561771ms","start":"2026-07-15T05:47:01.300626Z","end":"2026-07-15T05:47:01.536188Z","steps":["trace[746613166] 'process raft request' (duration: 235.325776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:01.536316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.38815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:01.536365Z","caller":"traceutil/trace.go:171","msg":"trace[875124663] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24842; }","duration":"120.510413ms","start":"2026-07-15T05:47:01.415843Z","end":"2026-07-15T05:47:01.536354Z","steps":["trace[875124663] 'agreement among raft nodes before linearized reading' (duration: 120.37121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:01.536534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.678323ms","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-07-15T05:47:01.536646Z","caller":"traceutil/trace.go:171","msg":"trace[1079735775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24842; }","duration":"198.823256ms","start":"2026-07-15T05:47:01.337794Z","end":"2026-07-15T05:47:01.536617Z","steps":["trace[1079735775] 'agreement among raft nodes before linearized reading' (duration: 198.479129ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:01.844644Z","caller":"traceutil/trace.go:171","msg":"trace[5053421] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"236.450297ms","start":"2026-07-15T05:47:01.608165Z","end":"2026-07-15T05:47:01.844615Z","steps":["trace[5053421] 'process raft request' (duration: 236.282654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:02.26688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.479497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:02.267102Z","caller":"traceutil/trace.go:171","msg":"trace[1613458956] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"416.055874ms","start":"2026-07-15T05:47:01.851006Z","end":"2026-07-15T05:47:02.267062Z","steps":["trace[1613458956] 'process raft request' (duration: 289.328492ms)","trace[1613458956] 'compare' (duration: 126.263193ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:02.267191Z","caller":"traceutil/trace.go:171","msg":"trace[1015758145] linearizableReadLoop","detail":"{readStateIndex:25652; appliedIndex:25651; }","duration":"392.110215ms","start":"2026-07-15T05:47:01.875027Z","end":"2026-07-15T05:47:02.267138Z","steps":["trace[1015758145] 'read index received' (duration: 265.394424ms)","trace[1015758145] 'applied index is now lower than readState.Index' (duration: 126.705451ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:02.267252Z","caller":"traceutil/trace.go:171","msg":"trace[746372437] transaction","detail":"{read_only:false; response_revision:24848; number_of_response:1; }","duration":"328.529575ms","start":"2026-07-15T05:47:01.938628Z","end":"2026-07-15T05:47:02.267157Z","steps":["trace[746372437] 'process raft request' (duration: 328.427823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:02.267281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:01.850969Z","time spent":"416.214918ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:02.267267Z","caller":"traceutil/trace.go:171","msg":"trace[980839913] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"144.762618ms","start":"2026-07-15T05:47:02.122472Z","end":"2026-07-15T05:47:02.267235Z","steps":["trace[980839913] 'process raft request' (duration: 144.644466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:02.26732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:01.9386Z","time spent":"328.689977ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:02.267502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.947834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:47:02.267554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.781158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23da8ede-db65-49f6-b7d7-ef7bf60e6d2d\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-07-15T05:47:02.267605Z","caller":"traceutil/trace.go:171","msg":"trace[1444614172] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23da8ede-db65-49f6-b7d7-ef7bf60e6d2d; range_end:; response_count:1; response_revision:24849; }","duration":"306.900709ms","start":"2026-07-15T05:47:01.960687Z","end":"2026-07-15T05:47:02.267587Z","steps":["trace[1444614172] 'agreement among raft nodes before linearized reading' (duration: 306.675815ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:02.267637Z","caller":"traceutil/trace.go:171","msg":"trace[210539958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24849; }","duration":"303.054956ms","start":"2026-07-15T05:47:01.964514Z","end":"2026-07-15T05:47:02.267569Z","steps":["trace[210539958] 'agreement among raft nodes before linearized reading' (duration: 302.866542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:02.267646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:01.960661Z","time spent":"306.976141ms","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3783,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23da8ede-db65-49f6-b7d7-ef7bf60e6d2d\" "} {"level":"warn","ts":"2026-07-15T05:47:02.267621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.553104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-07-15T05:47:02.267646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.473793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:02.267744Z","caller":"traceutil/trace.go:171","msg":"trace[373389784] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24849; }","duration":"392.733837ms","start":"2026-07-15T05:47:01.874982Z","end":"2026-07-15T05:47:02.267716Z","steps":["trace[373389784] 'agreement among raft nodes before linearized reading' (duration: 392.301719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:02.26778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:01.9645Z","time spent":"303.27018ms","remote":"127.0.0.1:47908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T05:47:02.26779Z","caller":"traceutil/trace.go:171","msg":"trace[1659292898] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:24849; }","duration":"303.678038ms","start":"2026-07-15T05:47:01.96408Z","end":"2026-07-15T05:47:02.267758Z","steps":["trace[1659292898] 'agreement among raft nodes before linearized reading' (duration: 303.335772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:02.267804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:01.874967Z","time spent":"392.826549ms","remote":"127.0.0.1:48100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-15T05:47:02.267857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:01.964075Z","time spent":"303.76937ms","remote":"127.0.0.1:34368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:47:04.457139Z","caller":"traceutil/trace.go:171","msg":"trace[247907941] linearizableReadLoop","detail":"{readStateIndex:25668; appliedIndex:25667; }","duration":"180.342571ms","start":"2026-07-15T05:47:04.276758Z","end":"2026-07-15T05:47:04.4571Z","steps":["trace[247907941] 'read index received' (duration: 180.231799ms)","trace[247907941] 'applied index is now lower than readState.Index' (duration: 109.472µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:04.457188Z","caller":"traceutil/trace.go:171","msg":"trace[1360669367] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"182.748707ms","start":"2026-07-15T05:47:04.274389Z","end":"2026-07-15T05:47:04.457138Z","steps":["trace[1360669367] 'process raft request' (duration: 182.563684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.457377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.596887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23da8ede-db65-49f6-b7d7-ef7bf60e6d2d\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-07-15T05:47:04.457431Z","caller":"traceutil/trace.go:171","msg":"trace[376404000] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23da8ede-db65-49f6-b7d7-ef7bf60e6d2d; range_end:; response_count:1; response_revision:24863; }","duration":"180.689378ms","start":"2026-07-15T05:47:04.276727Z","end":"2026-07-15T05:47:04.457417Z","steps":["trace[376404000] 'agreement among raft nodes before linearized reading' (duration: 180.460774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.742392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.51115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:04.742599Z","caller":"traceutil/trace.go:171","msg":"trace[1740525349] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"465.81908ms","start":"2026-07-15T05:47:04.276755Z","end":"2026-07-15T05:47:04.742574Z","steps":["trace[1740525349] 'process raft request' (duration: 217.048156ms)","trace[1740525349] 'compare' (duration: 247.807595ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:04.742713Z","caller":"traceutil/trace.go:171","msg":"trace[397388773] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"367.88087ms","start":"2026-07-15T05:47:04.374789Z","end":"2026-07-15T05:47:04.74267Z","steps":["trace[397388773] 'process raft request' (duration: 367.713487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.742768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:04.276735Z","time spent":"465.951383ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:04.74289Z","caller":"traceutil/trace.go:171","msg":"trace[1252987009] linearizableReadLoop","detail":"{readStateIndex:25671; appliedIndex:25668; }","duration":"204.599246ms","start":"2026-07-15T05:47:04.538283Z","end":"2026-07-15T05:47:04.742882Z","steps":["trace[1252987009] 'read index received' (duration: 32.161µs)","trace[1252987009] 'applied index is now lower than readState.Index' (duration: 204.566095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:04.742905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:04.374768Z","time spent":"368.028243ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:04.742906Z","caller":"traceutil/trace.go:171","msg":"trace[771240578] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"341.127616ms","start":"2026-07-15T05:47:04.401746Z","end":"2026-07-15T05:47:04.742873Z","steps":["trace[771240578] 'process raft request' (duration: 340.836391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.742981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.707918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-15T05:47:04.743005Z","caller":"traceutil/trace.go:171","msg":"trace[654732783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24867; }","duration":"204.76944ms","start":"2026-07-15T05:47:04.538229Z","end":"2026-07-15T05:47:04.742999Z","steps":["trace[654732783] 'agreement among raft nodes before linearized reading' (duration: 204.677998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.743068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:04.40172Z","time spent":"341.262159ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:05.051408Z","caller":"traceutil/trace.go:171","msg":"trace[1908145446] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"106.721198ms","start":"2026-07-15T05:47:04.944659Z","end":"2026-07-15T05:47:05.05138Z","steps":["trace[1908145446] 'process raft request' (duration: 106.476623ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:07.213217Z","caller":"traceutil/trace.go:171","msg":"trace[1579073885] linearizableReadLoop","detail":"{readStateIndex:25702; appliedIndex:25701; }","duration":"142.960174ms","start":"2026-07-15T05:47:07.07019Z","end":"2026-07-15T05:47:07.21315Z","steps":["trace[1579073885] 'read index received' (duration: 142.718559ms)","trace[1579073885] 'applied index is now lower than readState.Index' (duration: 239.705µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:07.213255Z","caller":"traceutil/trace.go:171","msg":"trace[1793507082] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"143.151457ms","start":"2026-07-15T05:47:07.07007Z","end":"2026-07-15T05:47:07.213222Z","steps":["trace[1793507082] 'process raft request' (duration: 142.918513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:07.213444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.229669ms","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-07-15T05:47:07.213493Z","caller":"traceutil/trace.go:171","msg":"trace[266594409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24894; }","duration":"143.327931ms","start":"2026-07-15T05:47:07.070149Z","end":"2026-07-15T05:47:07.213477Z","steps":["trace[266594409] 'agreement among raft nodes before linearized reading' (duration: 143.148718ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:07.392248Z","caller":"traceutil/trace.go:171","msg":"trace[1999727941] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"174.395708ms","start":"2026-07-15T05:47:07.217827Z","end":"2026-07-15T05:47:07.392222Z","steps":["trace[1999727941] 'process raft request' (duration: 174.179233ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:07.578565Z","caller":"traceutil/trace.go:171","msg":"trace[396888052] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"304.554385ms","start":"2026-07-15T05:47:07.27398Z","end":"2026-07-15T05:47:07.578535Z","steps":["trace[396888052] 'process raft request' (duration: 283.97537ms)","trace[396888052] 'compare' (duration: 20.399831ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:07.578741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:07.273948Z","time spent":"304.703308ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:07.57889Z","caller":"traceutil/trace.go:171","msg":"trace[799237369] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"291.450453ms","start":"2026-07-15T05:47:07.287428Z","end":"2026-07-15T05:47:07.578879Z","steps":["trace[799237369] 'process raft request' (duration: 291.056036ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:07.578851Z","caller":"traceutil/trace.go:171","msg":"trace[1996725853] linearizableReadLoop","detail":"{readStateIndex:25705; appliedIndex:25703; }","duration":"132.584054ms","start":"2026-07-15T05:47:07.446232Z","end":"2026-07-15T05:47:07.578816Z","steps":["trace[1996725853] 'read index received' (duration: 111.708354ms)","trace[1996725853] 'applied index is now lower than readState.Index' (duration: 20.87227ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:07.579083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.826218ms","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-07-15T05:47:07.579147Z","caller":"traceutil/trace.go:171","msg":"trace[2017818125] 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:24899; }","duration":"132.91336ms","start":"2026-07-15T05:47:07.446215Z","end":"2026-07-15T05:47:07.579128Z","steps":["trace[2017818125] 'agreement among raft nodes before linearized reading' (duration: 132.784737ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:18.022953Z","caller":"traceutil/trace.go:171","msg":"trace[1218513207] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"124.06987ms","start":"2026-07-15T05:47:17.898853Z","end":"2026-07-15T05:47:18.022923Z","steps":["trace[1218513207] 'process raft request' (duration: 123.818625ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:18.035932Z","caller":"traceutil/trace.go:171","msg":"trace[15234132] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"135.530201ms","start":"2026-07-15T05:47:17.900376Z","end":"2026-07-15T05:47:18.035906Z","steps":["trace[15234132] 'process raft request' (duration: 135.063352ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:18.612096Z","caller":"traceutil/trace.go:171","msg":"trace[1871671885] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"110.452891ms","start":"2026-07-15T05:47:18.501405Z","end":"2026-07-15T05:47:18.611858Z","steps":["trace[1871671885] 'process raft request' (duration: 110.295068ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:18.949048Z","caller":"traceutil/trace.go:171","msg":"trace[888335404] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"161.276155ms","start":"2026-07-15T05:47:18.787745Z","end":"2026-07-15T05:47:18.949021Z","steps":["trace[888335404] 'process raft request' (duration: 161.127253ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:19.091622Z","caller":"traceutil/trace.go:171","msg":"trace[1856094660] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"303.092507ms","start":"2026-07-15T05:47:18.788508Z","end":"2026-07-15T05:47:19.091601Z","steps":["trace[1856094660] 'process raft request' (duration: 302.940754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.091748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:18.788476Z","time spent":"303.207618ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:19.091793Z","caller":"traceutil/trace.go:171","msg":"trace[1093717233] linearizableReadLoop","detail":"{readStateIndex:25812; appliedIndex:25810; }","duration":"295.302417ms","start":"2026-07-15T05:47:18.796466Z","end":"2026-07-15T05:47:19.091768Z","steps":["trace[1093717233] 'read index received' (duration: 152.947075ms)","trace[1093717233] 'applied index is now lower than readState.Index' (duration: 142.352912ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:19.091912Z","caller":"traceutil/trace.go:171","msg":"trace[73557740] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"296.489451ms","start":"2026-07-15T05:47:18.795411Z","end":"2026-07-15T05:47:19.091901Z","steps":["trace[73557740] 'process raft request' (duration: 296.152404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.09202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.550152ms","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-07-15T05:47:19.092054Z","caller":"traceutil/trace.go:171","msg":"trace[415879164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25002; }","duration":"295.612504ms","start":"2026-07-15T05:47:18.796432Z","end":"2026-07-15T05:47:19.092044Z","steps":["trace[415879164] 'agreement among raft nodes before linearized reading' (duration: 295.497692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.092034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.411976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-15T05:47:19.092064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.2934ms","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-07-15T05:47:19.092097Z","caller":"traceutil/trace.go:171","msg":"trace[462979014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25002; }","duration":"238.537098ms","start":"2026-07-15T05:47:18.853546Z","end":"2026-07-15T05:47:19.092083Z","steps":["trace[462979014] 'agreement among raft nodes before linearized reading' (duration: 238.368325ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:19.092105Z","caller":"traceutil/trace.go:171","msg":"trace[421089017] 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:25002; }","duration":"229.377622ms","start":"2026-07-15T05:47:18.862707Z","end":"2026-07-15T05:47:19.092085Z","steps":["trace[421089017] 'agreement among raft nodes before linearized reading' (duration: 229.308381ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:19.408251Z","caller":"traceutil/trace.go:171","msg":"trace[1456050001] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"312.366695ms","start":"2026-07-15T05:47:19.095859Z","end":"2026-07-15T05:47:19.408225Z","steps":["trace[1456050001] 'process raft request' (duration: 272.691514ms)","trace[1456050001] 'compare' (duration: 39.385586ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:19.40831Z","caller":"traceutil/trace.go:171","msg":"trace[908840613] linearizableReadLoop","detail":"{readStateIndex:25814; appliedIndex:25812; }","duration":"311.862335ms","start":"2026-07-15T05:47:19.096429Z","end":"2026-07-15T05:47:19.408291Z","steps":["trace[908840613] 'read index received' (duration: 60.332547ms)","trace[908840613] 'applied index is now lower than readState.Index' (duration: 251.527918ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:19.408347Z","caller":"traceutil/trace.go:171","msg":"trace[1342031083] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"168.918511ms","start":"2026-07-15T05:47:19.239412Z","end":"2026-07-15T05:47:19.40833Z","steps":["trace[1342031083] 'process raft request' (duration: 168.82422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.408383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:19.095836Z","time spent":"312.473147ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:19.408459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.58442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:47:19.408473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.020767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:19.408554Z","caller":"traceutil/trace.go:171","msg":"trace[1398957589] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25005; }","duration":"312.1407ms","start":"2026-07-15T05:47:19.096395Z","end":"2026-07-15T05:47:19.408536Z","steps":["trace[1398957589] 'agreement among raft nodes before linearized reading' (duration: 311.977687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.408588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:19.096373Z","time spent":"312.209371ms","remote":"127.0.0.1:48134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-07-15T05:47:19.408615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.237484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-15T05:47:19.408657Z","caller":"traceutil/trace.go:171","msg":"trace[60647680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25005; }","duration":"149.301455ms","start":"2026-07-15T05:47:19.259346Z","end":"2026-07-15T05:47:19.408647Z","steps":["trace[60647680] 'agreement among raft nodes before linearized reading' (duration: 149.186533ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:19.408513Z","caller":"traceutil/trace.go:171","msg":"trace[645912365] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25005; }","duration":"162.671342ms","start":"2026-07-15T05:47:19.24583Z","end":"2026-07-15T05:47:19.408501Z","steps":["trace[645912365] 'agreement among raft nodes before linearized reading' (duration: 162.58223ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:29.977676Z","caller":"traceutil/trace.go:171","msg":"trace[1469994308] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"106.837991ms","start":"2026-07-15T05:47:29.870806Z","end":"2026-07-15T05:47:29.977644Z","steps":["trace[1469994308] 'process raft request' (duration: 50.691093ms)","trace[1469994308] 'compare' (duration: 55.989845ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:29.977693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.223729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:29.977984Z","caller":"traceutil/trace.go:171","msg":"trace[2119520365] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:25094; }","duration":"144.8057ms","start":"2026-07-15T05:47:29.833159Z","end":"2026-07-15T05:47:29.977964Z","steps":["trace[2119520365] 'count revisions from in-memory index tree' (duration: 144.168097ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:29.979229Z","caller":"traceutil/trace.go:171","msg":"trace[1664075313] linearizableReadLoop","detail":"{readStateIndex:25908; appliedIndex:25906; }","duration":"103.340342ms","start":"2026-07-15T05:47:29.875862Z","end":"2026-07-15T05:47:29.979202Z","steps":["trace[1664075313] 'read index received' (duration: 45.648635ms)","trace[1664075313] 'applied index is now lower than readState.Index' (duration: 57.690587ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:29.979347Z","caller":"traceutil/trace.go:171","msg":"trace[1809292605] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"107.587654ms","start":"2026-07-15T05:47:29.871726Z","end":"2026-07-15T05:47:29.979314Z","steps":["trace[1809292605] 'process raft request' (duration: 107.321799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:29.979462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.557906ms","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-07-15T05:47:29.979543Z","caller":"traceutil/trace.go:171","msg":"trace[1972510181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25096; }","duration":"103.69311ms","start":"2026-07-15T05:47:29.875832Z","end":"2026-07-15T05:47:29.979526Z","steps":["trace[1972510181] 'agreement among raft nodes before linearized reading' (duration: 103.467305ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:30.21276Z","caller":"traceutil/trace.go:171","msg":"trace[1310991650] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"147.197956ms","start":"2026-07-15T05:47:30.065536Z","end":"2026-07-15T05:47:30.212734Z","steps":["trace[1310991650] 'process raft request' (duration: 147.043193ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:30.354954Z","caller":"traceutil/trace.go:171","msg":"trace[1651993270] linearizableReadLoop","detail":"{readStateIndex:25912; appliedIndex:25909; }","duration":"226.549268ms","start":"2026-07-15T05:47:30.128381Z","end":"2026-07-15T05:47:30.35493Z","steps":["trace[1651993270] 'read index received' (duration: 84.271507ms)","trace[1651993270] 'applied index is now lower than readState.Index' (duration: 142.276651ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:30.354995Z","caller":"traceutil/trace.go:171","msg":"trace[1565245617] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"283.44991ms","start":"2026-07-15T05:47:30.071509Z","end":"2026-07-15T05:47:30.354958Z","steps":["trace[1565245617] 'process raft request' (duration: 253.423344ms)","trace[1565245617] 'compare' (duration: 29.843482ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:30.355032Z","caller":"traceutil/trace.go:171","msg":"trace[467779113] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"254.213119ms","start":"2026-07-15T05:47:30.100778Z","end":"2026-07-15T05:47:30.354991Z","steps":["trace[467779113] 'process raft request' (duration: 254.104177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:30.355106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.60152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:47:30.35515Z","caller":"traceutil/trace.go:171","msg":"trace[458814113] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25100; }","duration":"176.701971ms","start":"2026-07-15T05:47:30.178436Z","end":"2026-07-15T05:47:30.355138Z","steps":["trace[458814113] 'agreement among raft nodes before linearized reading' (duration: 176.627019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:30.355259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.873514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:30.355301Z","caller":"traceutil/trace.go:171","msg":"trace[124529925] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25100; }","duration":"226.982767ms","start":"2026-07-15T05:47:30.128309Z","end":"2026-07-15T05:47:30.355292Z","steps":["trace[124529925] 'agreement among raft nodes before linearized reading' (duration: 226.748332ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:30.764908Z","caller":"traceutil/trace.go:171","msg":"trace[911181978] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"113.812605ms","start":"2026-07-15T05:47:30.651077Z","end":"2026-07-15T05:47:30.76489Z","steps":["trace[911181978] 'process raft request' (duration: 113.699713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:31.043256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.18926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:47:31.04333Z","caller":"traceutil/trace.go:171","msg":"trace[735837605] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25101; }","duration":"139.292343ms","start":"2026-07-15T05:47:30.904019Z","end":"2026-07-15T05:47:31.043311Z","steps":["trace[735837605] 'count revisions from in-memory index tree' (duration: 139.115549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:31.043483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.910819ms","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-07-15T05:47:31.043555Z","caller":"traceutil/trace.go:171","msg":"trace[31590303] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25101; }","duration":"133.991961ms","start":"2026-07-15T05:47:30.909539Z","end":"2026-07-15T05:47:31.043531Z","steps":["trace[31590303] 'count revisions from in-memory index tree' (duration: 133.843218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:33.720683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.226468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-15T05:47:33.720772Z","caller":"traceutil/trace.go:171","msg":"trace[1727787694] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25122; }","duration":"107.365261ms","start":"2026-07-15T05:47:33.613385Z","end":"2026-07-15T05:47:33.72075Z","steps":["trace[1727787694] 'range keys from in-memory index tree' (duration: 107.048935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:34.157587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.579637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:34.15768Z","caller":"traceutil/trace.go:171","msg":"trace[938803364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25123; }","duration":"191.73706ms","start":"2026-07-15T05:47:33.965922Z","end":"2026-07-15T05:47:34.157659Z","steps":["trace[938803364] 'range keys from in-memory index tree' (duration: 191.403273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:34.157764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.317514ms","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-07-15T05:47:34.157824Z","caller":"traceutil/trace.go:171","msg":"trace[2117431894] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25123; }","duration":"324.406046ms","start":"2026-07-15T05:47:33.833402Z","end":"2026-07-15T05:47:34.157808Z","steps":["trace[2117431894] 'range keys from in-memory index tree' (duration: 324.253163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:34.157864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:33.833385Z","time spent":"324.469217ms","remote":"127.0.0.1:48348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-15T05:47:34.158072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.521729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:34.15823Z","caller":"traceutil/trace.go:171","msg":"trace[2145484528] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"168.542355ms","start":"2026-07-15T05:47:33.989659Z","end":"2026-07-15T05:47:34.158202Z","steps":["trace[2145484528] 'process raft request' (duration: 42.751031ms)","trace[2145484528] 'compare' (duration: 124.978219ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:34.313402Z","caller":"traceutil/trace.go:171","msg":"trace[392474144] linearizableReadLoop","detail":"{readStateIndex:25938; appliedIndex:25936; }","duration":"312.653691ms","start":"2026-07-15T05:47:34.000722Z","end":"2026-07-15T05:47:34.313376Z","steps":["trace[392474144] 'read index received' (duration: 31.704778ms)","trace[392474144] 'applied index is now lower than readState.Index' (duration: 280.947813ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:34.313631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.886995ms","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-07-15T05:47:34.313581Z","caller":"traceutil/trace.go:171","msg":"trace[757187545] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"317.908111ms","start":"2026-07-15T05:47:33.995613Z","end":"2026-07-15T05:47:34.313521Z","steps":["trace[757187545] 'process raft request' (duration: 275.565419ms)","trace[757187545] 'compare' (duration: 42.078287ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:34.313675Z","caller":"traceutil/trace.go:171","msg":"trace[585315240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25125; }","duration":"312.980157ms","start":"2026-07-15T05:47:34.000682Z","end":"2026-07-15T05:47:34.313662Z","steps":["trace[585315240] 'agreement among raft nodes before linearized reading' (duration: 312.804604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:34.313707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:34.000669Z","time spent":"313.031688ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T05:47:34.313876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:33.995579Z","time spent":"318.122666ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:34.317944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.858978ms","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-07-15T05:47:34.31798Z","caller":"traceutil/trace.go:171","msg":"trace[933559266] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25126; }","duration":"232.92461ms","start":"2026-07-15T05:47:34.085047Z","end":"2026-07-15T05:47:34.317971Z","steps":["trace[933559266] 'agreement among raft nodes before linearized reading' (duration: 232.821248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:34.318031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.018774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:34.31812Z","caller":"traceutil/trace.go:171","msg":"trace[338101485] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25126; }","duration":"156.150236ms","start":"2026-07-15T05:47:34.161951Z","end":"2026-07-15T05:47:34.318101Z","steps":["trace[338101485] 'agreement among raft nodes before linearized reading' (duration: 155.993083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:34.318161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.473871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:34.318267Z","caller":"traceutil/trace.go:171","msg":"trace[1608360882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25126; }","duration":"158.627974ms","start":"2026-07-15T05:47:34.159612Z","end":"2026-07-15T05:47:34.31824Z","steps":["trace[1608360882] 'agreement among raft nodes before linearized reading' (duration: 158.471041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:44.722918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.926414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:44.723149Z","caller":"traceutil/trace.go:171","msg":"trace[404228472] linearizableReadLoop","detail":"{readStateIndex:26016; appliedIndex:26015; }","duration":"300.843054ms","start":"2026-07-15T05:47:44.422286Z","end":"2026-07-15T05:47:44.723129Z","steps":["trace[404228472] 'read index received' (duration: 21.082695ms)","trace[404228472] 'applied index is now lower than readState.Index' (duration: 279.759089ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:44.723298Z","caller":"traceutil/trace.go:171","msg":"trace[1900815310] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"297.738794ms","start":"2026-07-15T05:47:44.425533Z","end":"2026-07-15T05:47:44.723271Z","steps":["trace[1900815310] 'process raft request' (duration: 297.497929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:44.723417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.107088ms","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-07-15T05:47:44.723465Z","caller":"traceutil/trace.go:171","msg":"trace[1165046149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25202; }","duration":"301.196771ms","start":"2026-07-15T05:47:44.422254Z","end":"2026-07-15T05:47:44.723451Z","steps":["trace[1165046149] 'agreement among raft nodes before linearized reading' (duration: 301.011407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:44.723505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:44.42224Z","time spent":"301.256242ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T05:47:44.723648Z","caller":"traceutil/trace.go:171","msg":"trace[1871639234] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"376.71072ms","start":"2026-07-15T05:47:44.346418Z","end":"2026-07-15T05:47:44.723129Z","steps":["trace[1871639234] 'process raft request' (duration: 246.47132ms)","trace[1871639234] 'compare' (duration: 129.543356ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:44.723813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:44.346391Z","time spent":"377.353532ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:44.821572Z","caller":"traceutil/trace.go:171","msg":"trace[365624537] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"152.252572ms","start":"2026-07-15T05:47:44.669297Z","end":"2026-07-15T05:47:44.821549Z","steps":["trace[365624537] 'process raft request' (duration: 152.078319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:44.821844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.062966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-07-15T05:47:44.821917Z","caller":"traceutil/trace.go:171","msg":"trace[1948245973] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25203; }","duration":"216.139538ms","start":"2026-07-15T05:47:44.605756Z","end":"2026-07-15T05:47:44.821896Z","steps":["trace[1948245973] 'agreement among raft nodes before linearized reading' (duration: 215.809072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:44.821951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.117251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-15T05:47:44.822027Z","caller":"traceutil/trace.go:171","msg":"trace[1663285661] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25203; }","duration":"310.222123ms","start":"2026-07-15T05:47:44.511788Z","end":"2026-07-15T05:47:44.82201Z","steps":["trace[1663285661] 'agreement among raft nodes before linearized reading' (duration: 309.727334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:44.822085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:44.511779Z","time spent":"310.297485ms","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-15T05:47:45.057236Z","caller":"traceutil/trace.go:171","msg":"trace[114838479] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"330.090175ms","start":"2026-07-15T05:47:44.727106Z","end":"2026-07-15T05:47:45.057196Z","steps":["trace[114838479] 'process raft request' (duration: 329.767079ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:45.057269Z","caller":"traceutil/trace.go:171","msg":"trace[1554500778] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"245.987362ms","start":"2026-07-15T05:47:44.811253Z","end":"2026-07-15T05:47:45.057241Z","steps":["trace[1554500778] 'process raft request' (duration: 245.842809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:45.057437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:44.727084Z","time spent":"330.240468ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:45.057561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.583835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-15T05:47:45.057529Z","caller":"traceutil/trace.go:171","msg":"trace[1780685878] linearizableReadLoop","detail":"{readStateIndex:26020; appliedIndex:26018; }","duration":"231.194478ms","start":"2026-07-15T05:47:44.825955Z","end":"2026-07-15T05:47:45.057149Z","steps":["trace[1780685878] 'read index received' (duration: 231.023935ms)","trace[1780685878] 'applied index is now lower than readState.Index' (duration: 169.183µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:45.057613Z","caller":"traceutil/trace.go:171","msg":"trace[1248310728] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25205; }","duration":"231.650676ms","start":"2026-07-15T05:47:44.825949Z","end":"2026-07-15T05:47:45.0576Z","steps":["trace[1248310728] 'agreement among raft nodes before linearized reading' (duration: 231.437092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:45.267437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.81428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:45.26773Z","caller":"traceutil/trace.go:171","msg":"trace[732984639] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"173.570011ms","start":"2026-07-15T05:47:45.094141Z","end":"2026-07-15T05:47:45.267711Z","steps":["trace[732984639] 'process raft request' (duration: 173.405118ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:45.267934Z","caller":"traceutil/trace.go:171","msg":"trace[1751776907] linearizableReadLoop","detail":"{readStateIndex:26021; appliedIndex:26020; }","duration":"210.292586ms","start":"2026-07-15T05:47:45.057611Z","end":"2026-07-15T05:47:45.267904Z","steps":["trace[1751776907] 'read index received' (duration: 52.941417ms)","trace[1751776907] 'applied index is now lower than readState.Index' (duration: 157.348169ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:45.267966Z","caller":"traceutil/trace.go:171","msg":"trace[1491414915] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"441.056094ms","start":"2026-07-15T05:47:44.826872Z","end":"2026-07-15T05:47:45.267928Z","steps":["trace[1491414915] 'process raft request' (duration: 283.666973ms)","trace[1491414915] 'compare' (duration: 156.254879ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:45.268126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:44.826848Z","time spent":"441.189787ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:45.268113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.900114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:45.268201Z","caller":"traceutil/trace.go:171","msg":"trace[589922546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25207; }","duration":"301.031127ms","start":"2026-07-15T05:47:44.967151Z","end":"2026-07-15T05:47:45.268182Z","steps":["trace[589922546] 'agreement among raft nodes before linearized reading' (duration: 300.864623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:45.268309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:44.967129Z","time spent":"301.17ms","remote":"127.0.0.1:47892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T05:47:45.480747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.951555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:45.481086Z","caller":"traceutil/trace.go:171","msg":"trace[199296969] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"114.07356ms","start":"2026-07-15T05:47:45.366991Z","end":"2026-07-15T05:47:45.481064Z","steps":["trace[199296969] 'process raft request' (duration: 114.011658ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:45.481185Z","caller":"traceutil/trace.go:171","msg":"trace[707886938] linearizableReadLoop","detail":"{readStateIndex:26024; appliedIndex:26022; }","duration":"213.196441ms","start":"2026-07-15T05:47:45.26798Z","end":"2026-07-15T05:47:45.481176Z","steps":["trace[707886938] 'read index received' (duration: 107.703636ms)","trace[707886938] 'applied index is now lower than readState.Index' (duration: 105.492165ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:45.481243Z","caller":"traceutil/trace.go:171","msg":"trace[474160245] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"288.919894ms","start":"2026-07-15T05:47:45.192276Z","end":"2026-07-15T05:47:45.481196Z","steps":["trace[474160245] 'process raft request' (duration: 288.579468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:45.481313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.18536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-07-15T05:47:45.481264Z","caller":"traceutil/trace.go:171","msg":"trace[702538724] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"299.244572ms","start":"2026-07-15T05:47:45.181969Z","end":"2026-07-15T05:47:45.481214Z","steps":["trace[702538724] 'process raft request' (duration: 193.734237ms)","trace[702538724] 'compare' (duration: 104.769001ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:45.481336Z","caller":"traceutil/trace.go:171","msg":"trace[978343601] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25210; }","duration":"374.263642ms","start":"2026-07-15T05:47:45.107064Z","end":"2026-07-15T05:47:45.481328Z","steps":["trace[978343601] 'agreement among raft nodes before linearized reading' (duration: 374.1691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:45.481354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:45.107043Z","time spent":"374.306982ms","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1886,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"warn","ts":"2026-07-15T05:47:45.481397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.974407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:45.481472Z","caller":"traceutil/trace.go:171","msg":"trace[448022339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25210; }","duration":"212.090139ms","start":"2026-07-15T05:47:45.269365Z","end":"2026-07-15T05:47:45.481455Z","steps":["trace[448022339] 'agreement among raft nodes before linearized reading' (duration: 211.972227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:45.481508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.820445ms","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-07-15T05:47:45.481492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.582449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18231"} {"level":"info","ts":"2026-07-15T05:47:45.481529Z","caller":"traceutil/trace.go:171","msg":"trace[915086005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25210; }","duration":"112.880146ms","start":"2026-07-15T05:47:45.368644Z","end":"2026-07-15T05:47:45.481524Z","steps":["trace[915086005] 'agreement among raft nodes before linearized reading' (duration: 112.827825ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:45.481552Z","caller":"traceutil/trace.go:171","msg":"trace[318621510] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25210; }","duration":"422.672652ms","start":"2026-07-15T05:47:45.058865Z","end":"2026-07-15T05:47:45.481537Z","steps":["trace[318621510] 'agreement among raft nodes before linearized reading' (duration: 422.524749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:45.481606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:45.058848Z","time spent":"422.745743ms","remote":"127.0.0.1:48104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18253,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-15T05:47:50.365342Z","caller":"traceutil/trace.go:171","msg":"trace[1792497096] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"111.074852ms","start":"2026-07-15T05:47:50.254237Z","end":"2026-07-15T05:47:50.365312Z","steps":["trace[1792497096] 'process raft request' (duration: 110.855858ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:50.995296Z","caller":"traceutil/trace.go:171","msg":"trace[270231615] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"253.898322ms","start":"2026-07-15T05:47:50.741363Z","end":"2026-07-15T05:47:50.995261Z","steps":["trace[270231615] 'process raft request' (duration: 253.506254ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:51.723432Z","caller":"traceutil/trace.go:171","msg":"trace[1170162904] linearizableReadLoop","detail":"{readStateIndex:26071; appliedIndex:26068; }","duration":"148.924408ms","start":"2026-07-15T05:47:51.574483Z","end":"2026-07-15T05:47:51.723407Z","steps":["trace[1170162904] 'read index received' (duration: 97.705625ms)","trace[1170162904] 'applied index is now lower than readState.Index' (duration: 51.217153ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:51.723486Z","caller":"traceutil/trace.go:171","msg":"trace[1134445658] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"150.723691ms","start":"2026-07-15T05:47:51.572728Z","end":"2026-07-15T05:47:51.723451Z","steps":["trace[1134445658] 'process raft request' (duration: 150.476937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:51.723708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.200024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-15T05:47:51.723751Z","caller":"traceutil/trace.go:171","msg":"trace[1553564933] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:25255; }","duration":"149.280166ms","start":"2026-07-15T05:47:51.574459Z","end":"2026-07-15T05:47:51.723739Z","steps":["trace[1553564933] 'agreement among raft nodes before linearized reading' (duration: 149.056321ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:51.724027Z","caller":"traceutil/trace.go:171","msg":"trace[1771387067] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"151.176091ms","start":"2026-07-15T05:47:51.57284Z","end":"2026-07-15T05:47:51.724016Z","steps":["trace[1771387067] 'process raft request' (duration: 150.520348ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:08.094658Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006690.wal"} {"level":"info","ts":"2026-07-15T05:48:11.797631Z","caller":"traceutil/trace.go:171","msg":"trace[654489992] linearizableReadLoop","detail":"{readStateIndex:26372; appliedIndex:26370; }","duration":"150.010059ms","start":"2026-07-15T05:48:11.647601Z","end":"2026-07-15T05:48:11.797612Z","steps":["trace[654489992] 'read index received' (duration: 79.883073ms)","trace[654489992] 'applied index is now lower than readState.Index' (duration: 70.125595ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:48:11.797883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.246303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:48:11.797982Z","caller":"traceutil/trace.go:171","msg":"trace[179144558] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25550; }","duration":"150.401216ms","start":"2026-07-15T05:48:11.647558Z","end":"2026-07-15T05:48:11.797959Z","steps":["trace[179144558] 'agreement among raft nodes before linearized reading' (duration: 150.160192ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:11.798238Z","caller":"traceutil/trace.go:171","msg":"trace[550538221] transaction","detail":"{read_only:false; response_revision:25550; number_of_response:1; }","duration":"118.82222ms","start":"2026-07-15T05:48:11.679359Z","end":"2026-07-15T05:48:11.798181Z","steps":["trace[550538221] 'process raft request' (duration: 118.205059ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:11.79834Z","caller":"traceutil/trace.go:171","msg":"trace[1738415363] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"173.472799ms","start":"2026-07-15T05:48:11.624821Z","end":"2026-07-15T05:48:11.798294Z","steps":["trace[1738415363] 'process raft request' (duration: 172.605112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:11.938324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.724388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:48:11.938438Z","caller":"traceutil/trace.go:171","msg":"trace[267126729] linearizableReadLoop","detail":"{readStateIndex:26374; appliedIndex:26373; }","duration":"140.707431ms","start":"2026-07-15T05:48:11.797714Z","end":"2026-07-15T05:48:11.938421Z","steps":["trace[267126729] 'read index received' (duration: 21.83681ms)","trace[267126729] 'applied index is now lower than readState.Index' (duration: 118.869071ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:48:11.938541Z","caller":"traceutil/trace.go:171","msg":"trace[1364487691] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"210.217824ms","start":"2026-07-15T05:48:11.728303Z","end":"2026-07-15T05:48:11.938521Z","steps":["trace[1364487691] 'process raft request' (duration: 91.21772ms)","trace[1364487691] 'compare' (duration: 118.507004ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:48:11.938811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.586245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:48:11.938868Z","caller":"traceutil/trace.go:171","msg":"trace[1174121114] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25551; }","duration":"255.682587ms","start":"2026-07-15T05:48:11.683173Z","end":"2026-07-15T05:48:11.938856Z","steps":["trace[1174121114] 'agreement among raft nodes before linearized reading' (duration: 255.485434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:11.938927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.578196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"warn","ts":"2026-07-15T05:48:11.938966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.814954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-15T05:48:11.939044Z","caller":"traceutil/trace.go:171","msg":"trace[1334313826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25551; }","duration":"125.910076ms","start":"2026-07-15T05:48:11.813117Z","end":"2026-07-15T05:48:11.939027Z","steps":["trace[1334313826] 'agreement among raft nodes before linearized reading' (duration: 125.741162ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:11.939123Z","caller":"traceutil/trace.go:171","msg":"trace[1752309703] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25551; }","duration":"154.770879ms","start":"2026-07-15T05:48:11.78424Z","end":"2026-07-15T05:48:11.939011Z","steps":["trace[1752309703] 'agreement among raft nodes before linearized reading' (duration: 154.429633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:11.939311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.39262ms","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-07-15T05:48:11.939172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.309176ms","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-07-15T05:48:11.939408Z","caller":"traceutil/trace.go:171","msg":"trace[750385610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25551; }","duration":"123.56009ms","start":"2026-07-15T05:48:11.815824Z","end":"2026-07-15T05:48:11.939385Z","steps":["trace[750385610] 'agreement among raft nodes before linearized reading' (duration: 123.136272ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:11.939395Z","caller":"traceutil/trace.go:171","msg":"trace[1745344648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25551; }","duration":"183.499422ms","start":"2026-07-15T05:48:11.755873Z","end":"2026-07-15T05:48:11.939372Z","steps":["trace[1745344648] 'agreement among raft nodes before linearized reading' (duration: 183.003583ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:12.239418Z","caller":"traceutil/trace.go:171","msg":"trace[1925171004] linearizableReadLoop","detail":"{readStateIndex:26375; appliedIndex:26374; }","duration":"295.307177ms","start":"2026-07-15T05:48:11.944087Z","end":"2026-07-15T05:48:12.239394Z","steps":["trace[1925171004] 'read index received' (duration: 295.138994ms)","trace[1925171004] 'applied index is now lower than readState.Index' (duration: 166.963µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:48:12.239478Z","caller":"traceutil/trace.go:171","msg":"trace[133584079] transaction","detail":"{read_only:false; response_revision:25552; number_of_response:1; }","duration":"295.731126ms","start":"2026-07-15T05:48:11.943707Z","end":"2026-07-15T05:48:12.239438Z","steps":["trace[133584079] 'process raft request' (duration: 295.529092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:12.239679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.569732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:48:12.239715Z","caller":"traceutil/trace.go:171","msg":"trace[1163452179] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25552; }","duration":"295.625603ms","start":"2026-07-15T05:48:11.944079Z","end":"2026-07-15T05:48:12.239705Z","steps":["trace[1163452179] 'agreement among raft nodes before linearized reading' (duration: 295.424339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:12.478853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.099231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:48:12.479024Z","caller":"traceutil/trace.go:171","msg":"trace[721876948] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"534.826964ms","start":"2026-07-15T05:48:11.944178Z","end":"2026-07-15T05:48:12.479005Z","steps":["trace[721876948] 'process raft request' (duration: 397.491917ms)","trace[721876948] 'compare' (duration: 137.00092ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:48:12.479095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:11.944167Z","time spent":"534.891846ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:48:12.479282Z","caller":"traceutil/trace.go:171","msg":"trace[590682880] linearizableReadLoop","detail":"{readStateIndex:26377; appliedIndex:26375; }","duration":"239.778192ms","start":"2026-07-15T05:48:12.239481Z","end":"2026-07-15T05:48:12.47926Z","steps":["trace[590682880] 'read index received' (duration: 102.074278ms)","trace[590682880] 'applied index is now lower than readState.Index' (duration: 137.703044ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:48:12.479411Z","caller":"traceutil/trace.go:171","msg":"trace[1575263086] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"535.195681ms","start":"2026-07-15T05:48:11.944205Z","end":"2026-07-15T05:48:12.4794Z","steps":["trace[1575263086] 'process raft request' (duration: 534.751622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:12.479513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:11.944178Z","time spent":"535.256672ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:48:12.479694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.465784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:48:12.479757Z","caller":"traceutil/trace.go:171","msg":"trace[1544730988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25555; }","duration":"514.596386ms","start":"2026-07-15T05:48:11.965148Z","end":"2026-07-15T05:48:12.479744Z","steps":["trace[1544730988] 'agreement among raft nodes before linearized reading' (duration: 514.506494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:12.479792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:11.96513Z","time spent":"514.655038ms","remote":"127.0.0.1:47892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T05:48:12.479786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.0743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:48:12.479848Z","caller":"traceutil/trace.go:171","msg":"trace[47997458] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25555; }","duration":"200.195852ms","start":"2026-07-15T05:48:12.279637Z","end":"2026-07-15T05:48:12.479833Z","steps":["trace[47997458] 'agreement among raft nodes before linearized reading' (duration: 200.04615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:12.479864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.328651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:48:12.479915Z","caller":"traceutil/trace.go:171","msg":"trace[1004790701] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25555; }","duration":"391.422033ms","start":"2026-07-15T05:48:12.08848Z","end":"2026-07-15T05:48:12.479902Z","steps":["trace[1004790701] 'agreement among raft nodes before linearized reading' (duration: 391.321401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:12.479954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:12.088462Z","time spent":"391.480614ms","remote":"127.0.0.1:36024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:48:12.479986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.416277ms","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-07-15T05:48:12.48003Z","caller":"traceutil/trace.go:171","msg":"trace[337288193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25555; }","duration":"472.463758ms","start":"2026-07-15T05:48:12.007554Z","end":"2026-07-15T05:48:12.480018Z","steps":["trace[337288193] 'agreement among raft nodes before linearized reading' (duration: 472.306355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:12.480063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:12.007489Z","time spent":"472.566ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T05:48:13.293105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.529819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:48:13.293187Z","caller":"traceutil/trace.go:171","msg":"trace[1382076901] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25558; }","duration":"434.612011ms","start":"2026-07-15T05:48:12.858544Z","end":"2026-07-15T05:48:13.293156Z","steps":["trace[1382076901] 'count revisions from in-memory index tree' (duration: 434.439918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.293219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:12.858491Z","time spent":"434.720633ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:48:13.293494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.71876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:48:13.293611Z","caller":"traceutil/trace.go:171","msg":"trace[674501005] linearizableReadLoop","detail":"{readStateIndex:26382; appliedIndex:26381; }","duration":"326.73348ms","start":"2026-07-15T05:48:12.966863Z","end":"2026-07-15T05:48:13.293597Z","steps":["trace[674501005] 'read index received' (duration: 210.841436ms)","trace[674501005] 'applied index is now lower than readState.Index' (duration: 115.890664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:48:13.293692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.835382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:48:13.293729Z","caller":"traceutil/trace.go:171","msg":"trace[1140690329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25559; }","duration":"326.874542ms","start":"2026-07-15T05:48:12.966844Z","end":"2026-07-15T05:48:13.293719Z","steps":["trace[1140690329] 'agreement among raft nodes before linearized reading' (duration: 326.810731ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:13.293695Z","caller":"traceutil/trace.go:171","msg":"trace[1494109577] transaction","detail":"{read_only:false; response_revision:25559; number_of_response:1; }","duration":"408.080351ms","start":"2026-07-15T05:48:12.885529Z","end":"2026-07-15T05:48:13.29361Z","steps":["trace[1494109577] 'process raft request' (duration: 292.165707ms)","trace[1494109577] 'compare' (duration: 115.565998ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:48:13.29394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:12.885497Z","time spent":"408.315445ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:48:13.293764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:12.966782Z","time spent":"326.975574ms","remote":"127.0.0.1:47892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T05:48:13.297418Z","caller":"traceutil/trace.go:171","msg":"trace[746763804] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"229.188149ms","start":"2026-07-15T05:48:13.068215Z","end":"2026-07-15T05:48:13.297404Z","steps":["trace[746763804] 'process raft request' (duration: 229.118907ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:13.374318Z","caller":"traceutil/trace.go:171","msg":"trace[1291047328] transaction","detail":"{read_only:false; response_revision:25561; number_of_response:1; }","duration":"123.246606ms","start":"2026-07-15T05:48:13.251039Z","end":"2026-07-15T05:48:13.374286Z","steps":["trace[1291047328] 'process raft request' (duration: 122.41425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.374758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.679776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-15T05:48:13.374839Z","caller":"traceutil/trace.go:171","msg":"trace[1408028932] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25561; }","duration":"145.775178ms","start":"2026-07-15T05:48:13.229047Z","end":"2026-07-15T05:48:13.374822Z","steps":["trace[1408028932] 'agreement among raft nodes before linearized reading' (duration: 145.510193ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:13.832766Z","caller":"traceutil/trace.go:171","msg":"trace[56690296] linearizableReadLoop","detail":"{readStateIndex:26385; appliedIndex:26384; }","duration":"458.463948ms","start":"2026-07-15T05:48:13.374276Z","end":"2026-07-15T05:48:13.83274Z","steps":["trace[56690296] 'read index received' (duration: 458.266544ms)","trace[56690296] 'applied index is now lower than readState.Index' (duration: 195.564µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:48:13.832945Z","caller":"traceutil/trace.go:171","msg":"trace[1287497508] transaction","detail":"{read_only:false; response_revision:25562; number_of_response:1; }","duration":"461.269503ms","start":"2026-07-15T05:48:13.371556Z","end":"2026-07-15T05:48:13.832826Z","steps":["trace[1287497508] 'process raft request' (duration: 460.998608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.833009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.76113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:48:13.833048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.03168ms","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-07-15T05:48:13.833093Z","caller":"traceutil/trace.go:171","msg":"trace[1974782504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25562; }","duration":"519.123822ms","start":"2026-07-15T05:48:13.313958Z","end":"2026-07-15T05:48:13.833081Z","steps":["trace[1974782504] 'agreement among raft nodes before linearized reading' (duration: 518.910848ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:13.833101Z","caller":"traceutil/trace.go:171","msg":"trace[1756462615] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25562; }","duration":"513.915063ms","start":"2026-07-15T05:48:13.319163Z","end":"2026-07-15T05:48:13.833078Z","steps":["trace[1756462615] 'agreement among raft nodes before linearized reading' (duration: 513.678998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.833141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.313943Z","time spent":"519.184033ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T05:48:13.833152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.319143Z","time spent":"513.997635ms","remote":"127.0.0.1:34366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:48:13.833245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.371531Z","time spent":"461.503417ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:48:13.861965Z","caller":"traceutil/trace.go:171","msg":"trace[1508247501] transaction","detail":"{read_only:false; response_revision:25563; number_of_response:1; }","duration":"482.323156ms","start":"2026-07-15T05:48:13.379614Z","end":"2026-07-15T05:48:13.861937Z","steps":["trace[1508247501] 'process raft request' (duration: 482.084632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.862242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.379596Z","time spent":"482.445218ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:48:13.862456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.465636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:48:13.862484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.699546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:48:13.862549Z","caller":"traceutil/trace.go:171","msg":"trace[1494995528] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:25565; }","duration":"417.826238ms","start":"2026-07-15T05:48:13.444708Z","end":"2026-07-15T05:48:13.862534Z","steps":["trace[1494995528] 'agreement among raft nodes before linearized reading' (duration: 417.717416ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:13.862568Z","caller":"traceutil/trace.go:171","msg":"trace[1372192794] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:25565; }","duration":"424.615199ms","start":"2026-07-15T05:48:13.437895Z","end":"2026-07-15T05:48:13.86251Z","steps":["trace[1372192794] 'agreement among raft nodes before linearized reading' (duration: 424.402135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.862593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.444678Z","time spent":"417.9076ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-07-15T05:48:13.862676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.437874Z","time spent":"424.784282ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-07-15T05:48:13.862823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.926744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:48:13.86289Z","caller":"traceutil/trace.go:171","msg":"trace[843431367] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25565; }","duration":"326.040428ms","start":"2026-07-15T05:48:13.536833Z","end":"2026-07-15T05:48:13.862874Z","steps":["trace[843431367] 'agreement among raft nodes before linearized reading' (duration: 325.926765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.862886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.885727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:48:13.862974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.498987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:48:13.862923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.40542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:48:13.862941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.536792Z","time spent":"326.13631ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"info","ts":"2026-07-15T05:48:13.863031Z","caller":"traceutil/trace.go:171","msg":"trace[1208012253] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:25565; }","duration":"325.591179ms","start":"2026-07-15T05:48:13.537426Z","end":"2026-07-15T05:48:13.863017Z","steps":["trace[1208012253] 'agreement among raft nodes before linearized reading' (duration: 325.505568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.863474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.537407Z","time spent":"326.030887ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"info","ts":"2026-07-15T05:48:13.862996Z","caller":"traceutil/trace.go:171","msg":"trace[976167005] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:25565; }","duration":"324.028219ms","start":"2026-07-15T05:48:13.538951Z","end":"2026-07-15T05:48:13.862979Z","steps":["trace[976167005] 'agreement among raft nodes before linearized reading' (duration: 323.849934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.862488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.339794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:48:13.86395Z","caller":"traceutil/trace.go:171","msg":"trace[1325772863] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25565; }","duration":"326.838103ms","start":"2026-07-15T05:48:13.537093Z","end":"2026-07-15T05:48:13.863931Z","steps":["trace[1325772863] 'agreement among raft nodes before linearized reading' (duration: 325.327533ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:13.863947Z","caller":"traceutil/trace.go:171","msg":"trace[1741274077] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25565; }","duration":"418.767427ms","start":"2026-07-15T05:48:13.444338Z","end":"2026-07-15T05:48:13.863106Z","steps":["trace[1741274077] 'agreement among raft nodes before linearized reading' (duration: 418.45163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.864081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.444306Z","time spent":"419.754286ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-07-15T05:48:13.863625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.53894Z","time spent":"324.63503ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-07-15T05:48:13.864088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.537086Z","time spent":"326.953315ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-07-15T05:48:13.863632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.085553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:48:13.864399Z","caller":"traceutil/trace.go:171","msg":"trace[534859714] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25565; }","duration":"419.751616ms","start":"2026-07-15T05:48:13.444633Z","end":"2026-07-15T05:48:13.864385Z","steps":["trace[534859714] 'agreement among raft nodes before linearized reading' (duration: 418.062013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:13.864519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.444617Z","time spent":"419.889878ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-07-15T05:48:14.375653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.010602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:48:14.375736Z","caller":"traceutil/trace.go:171","msg":"trace[845196800] linearizableReadLoop","detail":"{readStateIndex:26389; appliedIndex:26388; }","duration":"409.01638ms","start":"2026-07-15T05:48:13.966708Z","end":"2026-07-15T05:48:14.375725Z","steps":["trace[845196800] 'read index received' (duration: 150.899106ms)","trace[845196800] 'applied index is now lower than readState.Index' (duration: 258.116164ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:48:14.375782Z","caller":"traceutil/trace.go:171","msg":"trace[455205640] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"538.171859ms","start":"2026-07-15T05:48:13.837584Z","end":"2026-07-15T05:48:14.375756Z","steps":["trace[455205640] 'process raft request' (duration: 279.987694ms)","trace[455205640] 'compare' (duration: 257.000772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:48:14.375858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.140972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:48:14.375893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.837561Z","time spent":"538.270441ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:48:14.375938Z","caller":"traceutil/trace.go:171","msg":"trace[984414558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25566; }","duration":"409.254624ms","start":"2026-07-15T05:48:13.966659Z","end":"2026-07-15T05:48:14.375914Z","steps":["trace[984414558] 'agreement among raft nodes before linearized reading' (duration: 409.109031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:14.375961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.185978ms","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-07-15T05:48:14.376041Z","caller":"traceutil/trace.go:171","msg":"trace[2122464214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25566; }","duration":"132.31185ms","start":"2026-07-15T05:48:14.24371Z","end":"2026-07-15T05:48:14.376022Z","steps":["trace[2122464214] 'agreement among raft nodes before linearized reading' (duration: 132.081866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:48:14.375978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:48:13.966638Z","time spent":"409.332815ms","remote":"127.0.0.1:47908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T05:49:18.51414Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23238} {"level":"info","ts":"2026-07-15T05:49:18.649326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23238,"took":"130.776439ms","hash":2518421042,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":36614144,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-15T05:49:18.649378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2518421042,"revision":23238,"compact-revision":20096} {"level":"info","ts":"2026-07-15T05:51:28.008548Z","caller":"traceutil/trace.go:171","msg":"trace[1670747148] transaction","detail":"{read_only:false; response_revision:27505; number_of_response:1; }","duration":"132.415752ms","start":"2026-07-15T05:51:27.876104Z","end":"2026-07-15T05:51:28.00852Z","steps":["trace[1670747148] 'process raft request' (duration: 132.233027ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:54:18.520445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26130} {"level":"info","ts":"2026-07-15T05:54:18.664857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26130,"took":"139.765492ms","hash":1924975913,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":34365440,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-15T05:54:18.664942Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1924975913,"revision":26130,"compact-revision":23238} {"level":"info","ts":"2026-07-15T05:55:00.127411Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:55:00.132997Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-15T05:55:00.133153Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-15T05:59:18.526756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28778} {"level":"info","ts":"2026-07-15T05:59:18.65327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28778,"took":"123.465188ms","hash":171534768,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":30453760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T05:59:18.653447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":171534768,"revision":28778,"compact-revision":26130} {"level":"info","ts":"2026-07-15T06:04:18.535603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31001} {"level":"info","ts":"2026-07-15T06:04:18.650913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31001,"took":"110.744726ms","hash":4105044179,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T06:04:18.650969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4105044179,"revision":31001,"compact-revision":28778} {"level":"info","ts":"2026-07-15T06:09:18.542091Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33328} {"level":"info","ts":"2026-07-15T06:09:18.680379Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33328,"took":"134.261547ms","hash":3206506659,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":32104448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T06:09:18.680437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3206506659,"revision":33328,"compact-revision":31001} {"level":"info","ts":"2026-07-15T06:14:18.552498Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35656} {"level":"info","ts":"2026-07-15T06:14:18.692809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35656,"took":"137.129921ms","hash":3759166375,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T06:14:18.692861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3759166375,"revision":35656,"compact-revision":33328} {"level":"info","ts":"2026-07-15T06:16:10.646372Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T06:16:10.65188Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T06:16:10.652077Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-15T06:19:18.558028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37889} {"level":"info","ts":"2026-07-15T06:19:18.675295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37889,"took":"114.357825ms","hash":4261359169,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":31350784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T06:19:18.675367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4261359169,"revision":37889,"compact-revision":35656}