{"level":"warn","ts":"2026-10-14T17:47:26.393078Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:47:26.393267Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.166: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://162.253.55.166:2380","--initial-cluster=instance=https://162.253.55.166:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.166:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.166: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-10-14T17:47:26.393388Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:47:26.393413Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-10-14T17:47:26.393447Z","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-10-14T17:47:26.394788Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"]} {"level":"info","ts":"2026-10-14T17:47:26.394977Z","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://162.253.55.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.166: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-10-14T17:47:26.401389Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.892437ms"} {"level":"info","ts":"2026-10-14T17:47:26.411258Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7481851e1bcdf3c","cluster-id":"bb367d182eb83f54"} {"level":"info","ts":"2026-10-14T17:47:26.411407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:47:26.41145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 0"} {"level":"info","ts":"2026-10-14T17:47:26.41147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7481851e1bcdf3c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:47:26.411487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 1"} {"level":"info","ts":"2026-10-14T17:47:26.411565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"warn","ts":"2026-10-14T17:47:26.419394Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:47:26.421539Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:47:26.423112Z","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-10-14T17:47:26.426787Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7481851e1bcdf3c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:47:26.427323Z","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-10-14T17:47:26.427425Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7481851e1bcdf3c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:47:26.427668Z","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-10-14T17:47:26.427746Z","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-10-14T17:47:26.428872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"info","ts":"2026-10-14T17:47:26.429876Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","added-peer-id":"b7481851e1bcdf3c","added-peer-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-10-14T17:47:26.432857Z","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-10-14T17:47:26.433126Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-10-14T17:47:26.433758Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-10-14T17:47:26.433224Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7481851e1bcdf3c","initial-advertise-peer-urls":["https://162.253.55.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:47:26.433258Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:47:26.512191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:47:26.512249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:47:26.5123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgPreVoteResp from b7481851e1bcdf3c at term 1"} {"level":"info","ts":"2026-10-14T17:47:26.512323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:47:26.512382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgVoteResp from b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-10-14T17:47:26.5124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became leader at term 2"} {"level":"info","ts":"2026-10-14T17:47:26.512415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7481851e1bcdf3c elected leader b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-10-14T17:47:26.531977Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:47:26.547123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:47:26.547135Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:47:26.547178Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:47:26.547125Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7481851e1bcdf3c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.166:2379]}","request-path":"/0/members/b7481851e1bcdf3c/attributes","cluster-id":"bb367d182eb83f54","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:47:26.547447Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:47:26.547514Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:47:26.547501Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:47:26.547631Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:47:26.55107Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.166:2379"} {"level":"info","ts":"2026-10-14T17:47:26.551345Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:47:28.797076Z","caller":"traceutil/trace.go:171","msg":"trace[822641101] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:6; }","duration":"114.815798ms","start":"2026-10-14T17:47:28.682232Z","end":"2026-10-14T17:47:28.797047Z","steps":["trace[822641101] 'read index received' (duration: 111.400127ms)","trace[822641101] 'applied index is now lower than readState.Index' (duration: 3.414551ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:28.797218Z","caller":"traceutil/trace.go:171","msg":"trace[202180473] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"115.216305ms","start":"2026-10-14T17:47:28.681991Z","end":"2026-10-14T17:47:28.797207Z","steps":["trace[202180473] 'process raft request' (duration: 111.529829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:28.797448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.189985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:47:28.797574Z","caller":"traceutil/trace.go:171","msg":"trace[1732152145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4; }","duration":"115.347587ms","start":"2026-10-14T17:47:28.682209Z","end":"2026-10-14T17:47:28.797556Z","steps":["trace[1732152145] 'agreement among raft nodes before linearized reading' (duration: 115.078442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:28.800652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.029936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:47:28.80074Z","caller":"traceutil/trace.go:171","msg":"trace[1175958954] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"118.146927ms","start":"2026-10-14T17:47:28.682574Z","end":"2026-10-14T17:47:28.800721Z","steps":["trace[1175958954] 'agreement among raft nodes before linearized reading' (duration: 117.994825ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:28.801012Z","caller":"traceutil/trace.go:171","msg":"trace[1613876299] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"118.2543ms","start":"2026-10-14T17:47:28.68271Z","end":"2026-10-14T17:47:28.800964Z","steps":["trace[1613876299] 'process raft request' (duration: 117.655969ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:28.801105Z","caller":"traceutil/trace.go:171","msg":"trace[300859435] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"108.398151ms","start":"2026-10-14T17:47:28.692682Z","end":"2026-10-14T17:47:28.80108Z","steps":["trace[300859435] 'process raft request' (duration: 107.851931ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:28.801171Z","caller":"traceutil/trace.go:171","msg":"trace[728900298] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"118.672178ms","start":"2026-10-14T17:47:28.682448Z","end":"2026-10-14T17:47:28.80112Z","steps":["trace[728900298] 'process raft request' (duration: 117.821032ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:28.801204Z","caller":"traceutil/trace.go:171","msg":"trace[191077543] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"118.27452ms","start":"2026-10-14T17:47:28.682797Z","end":"2026-10-14T17:47:28.801071Z","steps":["trace[191077543] 'process raft request' (duration: 117.599067ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:28.801232Z","caller":"traceutil/trace.go:171","msg":"trace[1287972204] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"108.826069ms","start":"2026-10-14T17:47:28.69233Z","end":"2026-10-14T17:47:28.801156Z","steps":["trace[1287972204] 'process raft request' (duration: 108.168007ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:28.801044Z","caller":"traceutil/trace.go:171","msg":"trace[1705148336] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"118.165008ms","start":"2026-10-14T17:47:28.682861Z","end":"2026-10-14T17:47:28.801026Z","steps":["trace[1705148336] 'process raft request' (duration: 117.601298ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:28.801452Z","caller":"traceutil/trace.go:171","msg":"trace[867091775] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"118.495634ms","start":"2026-10-14T17:47:28.682859Z","end":"2026-10-14T17:47:28.801355Z","steps":["trace[867091775] 'process raft request' (duration: 117.559957ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:10.965181Z","caller":"traceutil/trace.go:171","msg":"trace[950846250] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"139.61005ms","start":"2026-10-14T17:48:10.825528Z","end":"2026-10-14T17:48:10.965138Z","steps":["trace[950846250] 'process raft request' (duration: 139.457917ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:10.974667Z","caller":"traceutil/trace.go:171","msg":"trace[2113936741] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"116.239614ms","start":"2026-10-14T17:48:10.858411Z","end":"2026-10-14T17:48:10.974651Z","steps":["trace[2113936741] 'process raft request' (duration: 115.968978ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:10.97467Z","caller":"traceutil/trace.go:171","msg":"trace[183662143] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"125.92173ms","start":"2026-10-14T17:48:10.848713Z","end":"2026-10-14T17:48:10.974635Z","steps":["trace[183662143] 'process raft request' (duration: 125.516734ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:10.974857Z","caller":"traceutil/trace.go:171","msg":"trace[359611428] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"124.379042ms","start":"2026-10-14T17:48:10.850469Z","end":"2026-10-14T17:48:10.974848Z","steps":["trace[359611428] 'process raft request' (duration: 123.832942ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:10.974897Z","caller":"traceutil/trace.go:171","msg":"trace[725974457] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"124.28402ms","start":"2026-10-14T17:48:10.850607Z","end":"2026-10-14T17:48:10.974891Z","steps":["trace[725974457] 'process raft request' (duration: 123.73056ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:10.97481Z","caller":"traceutil/trace.go:171","msg":"trace[1729210890] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"114.709166ms","start":"2026-10-14T17:48:10.860048Z","end":"2026-10-14T17:48:10.974757Z","steps":["trace[1729210890] 'process raft request' (duration: 114.368639ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:10.97491Z","caller":"traceutil/trace.go:171","msg":"trace[1286701716] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"140.542329ms","start":"2026-10-14T17:48:10.834334Z","end":"2026-10-14T17:48:10.974876Z","steps":["trace[1286701716] 'process raft request' (duration: 139.853707ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:10.97486Z","caller":"traceutil/trace.go:171","msg":"trace[307365002] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"114.678275ms","start":"2026-10-14T17:48:10.860154Z","end":"2026-10-14T17:48:10.974832Z","steps":["trace[307365002] 'process raft request' (duration: 114.324248ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:10.97481Z","caller":"traceutil/trace.go:171","msg":"trace[1579306579] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"140.555038ms","start":"2026-10-14T17:48:10.834203Z","end":"2026-10-14T17:48:10.974758Z","steps":["trace[1579306579] 'process raft request' (duration: 139.909876ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:17.336653Z","caller":"traceutil/trace.go:171","msg":"trace[677194967] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"190.706164ms","start":"2026-10-14T17:48:17.145919Z","end":"2026-10-14T17:48:17.336626Z","steps":["trace[677194967] 'process raft request' (duration: 190.545531ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:17.53201Z","caller":"traceutil/trace.go:171","msg":"trace[601000300] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:669; }","duration":"237.533659ms","start":"2026-10-14T17:48:17.29445Z","end":"2026-10-14T17:48:17.531984Z","steps":["trace[601000300] 'read index received' (duration: 42.083548ms)","trace[601000300] 'applied index is now lower than readState.Index' (duration: 195.448661ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:17.532144Z","caller":"traceutil/trace.go:171","msg":"trace[476087693] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"369.318127ms","start":"2026-10-14T17:48:17.162815Z","end":"2026-10-14T17:48:17.532133Z","steps":["trace[476087693] 'process raft request' (duration: 369.117003ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:17.532127Z","caller":"traceutil/trace.go:171","msg":"trace[917293528] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"383.725591ms","start":"2026-10-14T17:48:17.148363Z","end":"2026-10-14T17:48:17.532089Z","steps":["trace[917293528] 'process raft request' (duration: 383.371265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:17.532301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.823284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:48:17.532331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.098645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vlqnm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T17:48:17.532393Z","caller":"traceutil/trace.go:171","msg":"trace[830992601] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vlqnm; range_end:; response_count:1; response_revision:647; }","duration":"125.202968ms","start":"2026-10-14T17:48:17.407174Z","end":"2026-10-14T17:48:17.532377Z","steps":["trace[830992601] 'agreement among raft nodes before linearized reading' (duration: 125.027634ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:17.5324Z","caller":"traceutil/trace.go:171","msg":"trace[2037840416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"237.979248ms","start":"2026-10-14T17:48:17.294394Z","end":"2026-10-14T17:48:17.532373Z","steps":["trace[2037840416] 'agreement among raft nodes before linearized reading' (duration: 237.796424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:17.5327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:17.162798Z","time spent":"369.373559ms","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T17:48:17.533097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:17.148348Z","time spent":"383.857443ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:48:17.928348Z","caller":"traceutil/trace.go:171","msg":"trace[358913144] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"376.920307ms","start":"2026-10-14T17:48:17.551401Z","end":"2026-10-14T17:48:17.928321Z","steps":["trace[358913144] 'process raft request' (duration: 376.751764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:17.928503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:17.551346Z","time spent":"377.075969ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:48:18.621044Z","caller":"traceutil/trace.go:171","msg":"trace[256337132] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"325.722792ms","start":"2026-10-14T17:48:18.295294Z","end":"2026-10-14T17:48:18.621017Z","steps":["trace[256337132] 'read index received' (duration: 324.899886ms)","trace[256337132] 'applied index is now lower than readState.Index' (duration: 821.766µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:18.621133Z","caller":"traceutil/trace.go:171","msg":"trace[1343713877] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"649.253092ms","start":"2026-10-14T17:48:17.971859Z","end":"2026-10-14T17:48:18.621112Z","steps":["trace[1343713877] 'process raft request' (duration: 648.623891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:18.621177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.861384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:48:18.621266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:17.971806Z","time spent":"649.373165ms","remote":"127.0.0.1:55048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:48:18.621311Z","caller":"traceutil/trace.go:171","msg":"trace[1158852195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"325.956116ms","start":"2026-10-14T17:48:18.295249Z","end":"2026-10-14T17:48:18.621205Z","steps":["trace[1158852195] 'agreement among raft nodes before linearized reading' (duration: 325.871555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:18.621361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:18.295229Z","time spent":"326.117929ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T17:48:35.140422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.866243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k5rzk\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-14T17:48:35.140796Z","caller":"traceutil/trace.go:171","msg":"trace[838643067] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k5rzk; range_end:; response_count:1; response_revision:816; }","duration":"633.066877ms","start":"2026-10-14T17:48:34.507451Z","end":"2026-10-14T17:48:35.140517Z","steps":["trace[838643067] 'range keys from in-memory index tree' (duration: 632.593408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:35.140884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:34.507432Z","time spent":"633.436024ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k5rzk\" "} {"level":"warn","ts":"2026-10-14T17:48:35.140432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.235931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:48:35.141189Z","caller":"traceutil/trace.go:171","msg":"trace[1504131390] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:816; }","duration":"597.982765ms","start":"2026-10-14T17:48:34.543098Z","end":"2026-10-14T17:48:35.141081Z","steps":["trace[1504131390] 'count revisions from in-memory index tree' (duration: 597.124539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:35.141375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:34.543072Z","time spent":"598.169639ms","remote":"127.0.0.1:55250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-14T17:48:49.097726Z","caller":"traceutil/trace.go:171","msg":"trace[39737492] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"113.3008ms","start":"2026-10-14T17:48:48.984392Z","end":"2026-10-14T17:48:49.097693Z","steps":["trace[39737492] 'process raft request' (duration: 113.071436ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:54.863488Z","caller":"traceutil/trace.go:171","msg":"trace[676484322] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"105.423825ms","start":"2026-10-14T17:48:54.758036Z","end":"2026-10-14T17:48:54.863459Z","steps":["trace[676484322] 'process raft request' (duration: 105.308013ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:30.082849Z","caller":"traceutil/trace.go:171","msg":"trace[2105123947] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"216.943152ms","start":"2026-10-14T17:49:29.865848Z","end":"2026-10-14T17:49:30.082791Z","steps":["trace[2105123947] 'process raft request' (duration: 216.646736ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:30.219176Z","caller":"traceutil/trace.go:171","msg":"trace[232517700] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"216.238968ms","start":"2026-10-14T17:49:30.0029Z","end":"2026-10-14T17:49:30.219139Z","steps":["trace[232517700] 'process raft request' (duration: 188.457008ms)","trace[232517700] 'compare' (duration: 27.576446ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:49:58.320465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.979786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:49:58.320739Z","caller":"traceutil/trace.go:171","msg":"trace[169686408] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"342.341028ms","start":"2026-10-14T17:49:57.978375Z","end":"2026-10-14T17:49:58.320716Z","steps":["trace[169686408] 'process raft request' (duration: 342.220955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:58.32084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:57.978344Z","time spent":"342.44786ms","remote":"127.0.0.1:55226","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-10-14T17:49:58.320974Z","caller":"traceutil/trace.go:171","msg":"trace[323437421] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"634.703828ms","start":"2026-10-14T17:49:57.686159Z","end":"2026-10-14T17:49:58.320863Z","steps":["trace[323437421] 'process raft request' (duration: 247.056841ms)","trace[323437421] 'compare' (duration: 386.651809ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:49:58.321246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:57.686128Z","time spent":"634.994554ms","remote":"127.0.0.1:55226","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-10-14T17:49:58.321365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.115446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89edc71e-2324-4fb9-9ce3-95a15408d648\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-14T17:49:58.321551Z","caller":"traceutil/trace.go:171","msg":"trace[159715807] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-89edc71e-2324-4fb9-9ce3-95a15408d648; range_end:; response_count:1; response_revision:1915; }","duration":"635.29258ms","start":"2026-10-14T17:49:57.686184Z","end":"2026-10-14T17:49:58.321477Z","steps":["trace[159715807] 'agreement among raft nodes before linearized reading' (duration: 634.829341ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:58.321568Z","caller":"traceutil/trace.go:171","msg":"trace[1371116336] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"634.730589ms","start":"2026-10-14T17:49:57.686227Z","end":"2026-10-14T17:49:58.320957Z","steps":["trace[1371116336] 'read index received' (duration: 246.99225ms)","trace[1371116336] 'applied index is now lower than readState.Index' (duration: 387.735169ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:49:58.321602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:57.686169Z","time spent":"635.418462ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89edc71e-2324-4fb9-9ce3-95a15408d648\" "} {"level":"warn","ts":"2026-10-14T17:49:58.321806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.957879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:49:58.321867Z","caller":"traceutil/trace.go:171","msg":"trace[1315797825] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1915; }","duration":"565.045101ms","start":"2026-10-14T17:49:57.756802Z","end":"2026-10-14T17:49:58.321847Z","steps":["trace[1315797825] 'agreement among raft nodes before linearized reading' (duration: 564.916458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:58.321961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:57.756745Z","time spent":"565.197845ms","remote":"127.0.0.1:51372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-14T17:49:58.322183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.896049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89edc71e-2324-4fb9-9ce3-95a15408d648\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-14T17:49:58.322175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.066061ms","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-10-14T17:49:58.322246Z","caller":"traceutil/trace.go:171","msg":"trace[4278935] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-89edc71e-2324-4fb9-9ce3-95a15408d648; range_end:; response_count:1; response_revision:1915; }","duration":"349.006721ms","start":"2026-10-14T17:49:57.973223Z","end":"2026-10-14T17:49:58.32223Z","steps":["trace[4278935] 'agreement among raft nodes before linearized reading' (duration: 348.787987ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:58.322271Z","caller":"traceutil/trace.go:171","msg":"trace[1298048605] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1915; }","duration":"493.160333ms","start":"2026-10-14T17:49:57.829081Z","end":"2026-10-14T17:49:58.322241Z","steps":["trace[1298048605] 'agreement among raft nodes before linearized reading' (duration: 492.936929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:58.322333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:57.829036Z","time spent":"493.283116ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-14T17:49:58.322279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:57.973202Z","time spent":"349.069162ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89edc71e-2324-4fb9-9ce3-95a15408d648\" "} {"level":"info","ts":"2026-10-14T17:49:58.502773Z","caller":"traceutil/trace.go:171","msg":"trace[762205927] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"174.756794ms","start":"2026-10-14T17:49:58.327968Z","end":"2026-10-14T17:49:58.502725Z","steps":["trace[762205927] 'process raft request' (duration: 134.551056ms)","trace[762205927] 'compare' (duration: 40.019945ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:58.54766Z","caller":"traceutil/trace.go:171","msg":"trace[1568734284] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"215.787208ms","start":"2026-10-14T17:49:58.331837Z","end":"2026-10-14T17:49:58.547624Z","steps":["trace[1568734284] 'process raft request' (duration: 215.535963ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:58.54797Z","caller":"traceutil/trace.go:171","msg":"trace[2041932918] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"207.21145ms","start":"2026-10-14T17:49:58.340632Z","end":"2026-10-14T17:49:58.547844Z","steps":["trace[2041932918] 'process raft request' (duration: 206.889694ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:59.006179Z","caller":"traceutil/trace.go:171","msg":"trace[473211318] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"453.329282ms","start":"2026-10-14T17:49:58.552822Z","end":"2026-10-14T17:49:59.006151Z","steps":["trace[473211318] 'process raft request' (duration: 453.161579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:59.006418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:58.552782Z","time spent":"453.488916ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:49:59.00959Z","caller":"traceutil/trace.go:171","msg":"trace[1001067922] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"400.046285ms","start":"2026-10-14T17:49:58.609516Z","end":"2026-10-14T17:49:59.009563Z","steps":["trace[1001067922] 'process raft request' (duration: 399.870452ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:59.009736Z","caller":"traceutil/trace.go:171","msg":"trace[1471416741] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1994; }","duration":"286.135557ms","start":"2026-10-14T17:49:58.723577Z","end":"2026-10-14T17:49:59.009713Z","steps":["trace[1471416741] 'read index received' (duration: 282.761255ms)","trace[1471416741] 'applied index is now lower than readState.Index' (duration: 3.372882ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:59.009792Z","caller":"traceutil/trace.go:171","msg":"trace[1474798520] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"173.487791ms","start":"2026-10-14T17:49:58.836287Z","end":"2026-10-14T17:49:59.009775Z","steps":["trace[1474798520] 'process raft request' (duration: 173.227396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:59.009788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:58.609501Z","time spent":"400.171388ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:49:59.009947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.356931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-14T17:49:59.009983Z","caller":"traceutil/trace.go:171","msg":"trace[480271447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1922; }","duration":"286.516224ms","start":"2026-10-14T17:49:58.723458Z","end":"2026-10-14T17:49:59.009974Z","steps":["trace[480271447] 'agreement among raft nodes before linearized reading' (duration: 286.362491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:50:14.65299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.386994ms","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-10-14T17:50:14.653104Z","caller":"traceutil/trace.go:171","msg":"trace[1063391969] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2047; }","duration":"220.526676ms","start":"2026-10-14T17:50:14.432548Z","end":"2026-10-14T17:50:14.653075Z","steps":["trace[1063391969] 'range keys from in-memory index tree' (duration: 220.16507ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:50:56.141132Z","caller":"traceutil/trace.go:171","msg":"trace[1169370754] linearizableReadLoop","detail":"{readStateIndex:2366; appliedIndex:2365; }","duration":"192.045955ms","start":"2026-10-14T17:50:55.949048Z","end":"2026-10-14T17:50:56.141093Z","steps":["trace[1169370754] 'read index received' (duration: 191.580226ms)","trace[1169370754] 'applied index is now lower than readState.Index' (duration: 462.659µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:50:56.141145Z","caller":"traceutil/trace.go:171","msg":"trace[2081796025] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"240.680896ms","start":"2026-10-14T17:50:55.900423Z","end":"2026-10-14T17:50:56.141104Z","steps":["trace[2081796025] 'process raft request' (duration: 240.311219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:50:56.141333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.272068ms","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-10-14T17:50:56.14142Z","caller":"traceutil/trace.go:171","msg":"trace[1581367578] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2278; }","duration":"192.395129ms","start":"2026-10-14T17:50:55.949005Z","end":"2026-10-14T17:50:56.1414Z","steps":["trace[1581367578] 'agreement among raft nodes before linearized reading' (duration: 192.179526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:50:56.379432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.358128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:50:56.379563Z","caller":"traceutil/trace.go:171","msg":"trace[212336862] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2278; }","duration":"187.524331ms","start":"2026-10-14T17:50:56.191982Z","end":"2026-10-14T17:50:56.379507Z","steps":["trace[212336862] 'count revisions from in-memory index tree' (duration: 187.213815ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:25.1143Z","caller":"traceutil/trace.go:171","msg":"trace[920762675] transaction","detail":"{read_only:false; response_revision:2947; number_of_response:1; }","duration":"105.153954ms","start":"2026-10-14T17:52:25.009114Z","end":"2026-10-14T17:52:25.114268Z","steps":["trace[920762675] 'process raft request' (duration: 104.97618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:52:25.655967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.256666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T17:52:25.656087Z","caller":"traceutil/trace.go:171","msg":"trace[590067488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2949; }","duration":"264.41671ms","start":"2026-10-14T17:52:25.39164Z","end":"2026-10-14T17:52:25.656057Z","steps":["trace[590067488] 'range keys from in-memory index tree' (duration: 264.058343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:52:25.65625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.995565ms","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-10-14T17:52:25.656425Z","caller":"traceutil/trace.go:171","msg":"trace[1396190749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2949; }","duration":"275.207659ms","start":"2026-10-14T17:52:25.381199Z","end":"2026-10-14T17:52:25.656406Z","steps":["trace[1396190749] 'range keys from in-memory index tree' (duration: 274.848593ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:30.779258Z","caller":"traceutil/trace.go:171","msg":"trace[17262944] transaction","detail":"{read_only:false; response_revision:2982; number_of_response:1; }","duration":"161.063699ms","start":"2026-10-14T17:52:30.618164Z","end":"2026-10-14T17:52:30.779227Z","steps":["trace[17262944] 'process raft request' (duration: 151.037583ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:13.655654Z","caller":"traceutil/trace.go:171","msg":"trace[1825377312] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"168.063918ms","start":"2026-10-14T17:57:13.487554Z","end":"2026-10-14T17:57:13.655618Z","steps":["trace[1825377312] 'process raft request' (duration: 167.774912ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:13.695868Z","caller":"traceutil/trace.go:171","msg":"trace[2101856139] linearizableReadLoop","detail":"{readStateIndex:5406; appliedIndex:5404; }","duration":"139.274196ms","start":"2026-10-14T17:57:13.556557Z","end":"2026-10-14T17:57:13.695831Z","steps":["trace[2101856139] 'read index received' (duration: 98.663238ms)","trace[2101856139] 'applied index is now lower than readState.Index' (duration: 40.609078ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:13.695962Z","caller":"traceutil/trace.go:171","msg":"trace[996854432] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"161.457634ms","start":"2026-10-14T17:57:13.534438Z","end":"2026-10-14T17:57:13.695896Z","steps":["trace[996854432] 'process raft request' (duration: 161.22436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:13.696302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.728144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e3b7db6-a5ee-490e-92ea-0dd02ed06735\" ","response":"range_response_count:1 size:3891"} {"level":"info","ts":"2026-10-14T17:57:13.696379Z","caller":"traceutil/trace.go:171","msg":"trace[164864001] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6e3b7db6-a5ee-490e-92ea-0dd02ed06735; range_end:; response_count:1; response_revision:5211; }","duration":"139.883497ms","start":"2026-10-14T17:57:13.556475Z","end":"2026-10-14T17:57:13.696359Z","steps":["trace[164864001] 'agreement among raft nodes before linearized reading' (duration: 139.561561ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:16.491732Z","caller":"traceutil/trace.go:171","msg":"trace[1895040514] linearizableReadLoop","detail":"{readStateIndex:5464; appliedIndex:5463; }","duration":"178.53445ms","start":"2026-10-14T17:57:16.313175Z","end":"2026-10-14T17:57:16.49171Z","steps":["trace[1895040514] 'read index received' (duration: 178.425509ms)","trace[1895040514] 'applied index is now lower than readState.Index' (duration: 108.021µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:16.491812Z","caller":"traceutil/trace.go:171","msg":"trace[273762703] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"179.451446ms","start":"2026-10-14T17:57:16.312309Z","end":"2026-10-14T17:57:16.49176Z","steps":["trace[273762703] 'process raft request' (duration: 179.236233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:16.491932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.720093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-tv4w2\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-10-14T17:57:16.491964Z","caller":"traceutil/trace.go:171","msg":"trace[1675839493] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-tv4w2; range_end:; response_count:1; response_revision:5265; }","duration":"178.826815ms","start":"2026-10-14T17:57:16.313124Z","end":"2026-10-14T17:57:16.49195Z","steps":["trace[1675839493] 'agreement among raft nodes before linearized reading' (duration: 178.664072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:20.493421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.718614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:57:20.493534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.444236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:57:20.493647Z","caller":"traceutil/trace.go:171","msg":"trace[1029778338] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:5422; }","duration":"122.65717ms","start":"2026-10-14T17:57:20.370966Z","end":"2026-10-14T17:57:20.493623Z","steps":["trace[1029778338] 'count revisions from in-memory index tree' (duration: 122.219122ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:20.493878Z","caller":"traceutil/trace.go:171","msg":"trace[1631175347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5422; }","duration":"198.252514ms","start":"2026-10-14T17:57:20.295598Z","end":"2026-10-14T17:57:20.493851Z","steps":["trace[1631175347] 'range keys from in-memory index tree' (duration: 197.593421ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:20.705319Z","caller":"traceutil/trace.go:171","msg":"trace[1592681041] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"196.872958ms","start":"2026-10-14T17:57:20.508415Z","end":"2026-10-14T17:57:20.705288Z","steps":["trace[1592681041] 'process raft request' (duration: 196.646423ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:20.705562Z","caller":"traceutil/trace.go:171","msg":"trace[683100046] linearizableReadLoop","detail":"{readStateIndex:5628; appliedIndex:5626; }","duration":"182.147807ms","start":"2026-10-14T17:57:20.523377Z","end":"2026-10-14T17:57:20.705525Z","steps":["trace[683100046] 'read index received' (duration: 181.619667ms)","trace[683100046] 'applied index is now lower than readState.Index' (duration: 526.45µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:20.70589Z","caller":"traceutil/trace.go:171","msg":"trace[117818985] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"197.456789ms","start":"2026-10-14T17:57:20.508418Z","end":"2026-10-14T17:57:20.705874Z","steps":["trace[117818985] 'process raft request' (duration: 196.822797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:20.706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.774315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.166\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T17:57:20.706044Z","caller":"traceutil/trace.go:171","msg":"trace[557892777] range","detail":"{range_begin:/registry/masterleases/162.253.55.166; range_end:; response_count:1; response_revision:5424; }","duration":"177.853938ms","start":"2026-10-14T17:57:20.528178Z","end":"2026-10-14T17:57:20.706032Z","steps":["trace[557892777] 'agreement among raft nodes before linearized reading' (duration: 177.624083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:20.706294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.919781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9df74b1d-b567-4cdc-99df-e9d28479c3a2\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-14T17:57:20.706327Z","caller":"traceutil/trace.go:171","msg":"trace[1202627991] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9df74b1d-b567-4cdc-99df-e9d28479c3a2; range_end:; response_count:1; response_revision:5424; }","duration":"182.997143ms","start":"2026-10-14T17:57:20.523315Z","end":"2026-10-14T17:57:20.706312Z","steps":["trace[1202627991] 'agreement among raft nodes before linearized reading' (duration: 182.909371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:20.818792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.465521ms","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-10-14T17:57:20.818878Z","caller":"traceutil/trace.go:171","msg":"trace[2112941329] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5426; }","duration":"100.594694ms","start":"2026-10-14T17:57:20.718266Z","end":"2026-10-14T17:57:20.81886Z","steps":["trace[2112941329] 'agreement among raft nodes before linearized reading' (duration: 100.307549ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:21.226177Z","caller":"traceutil/trace.go:171","msg":"trace[2073658501] linearizableReadLoop","detail":"{readStateIndex:5636; appliedIndex:5635; }","duration":"231.435175ms","start":"2026-10-14T17:57:20.994701Z","end":"2026-10-14T17:57:21.226136Z","steps":["trace[2073658501] 'read index received' (duration: 231.080668ms)","trace[2073658501] 'applied index is now lower than readState.Index' (duration: 353.277µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:21.226319Z","caller":"traceutil/trace.go:171","msg":"trace[418049580] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"252.499103ms","start":"2026-10-14T17:57:20.973758Z","end":"2026-10-14T17:57:21.226257Z","steps":["trace[418049580] 'process raft request' (duration: 252.121326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:21.226495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.761991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:57:21.226598Z","caller":"traceutil/trace.go:171","msg":"trace[1150155941] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5430; }","duration":"231.924734ms","start":"2026-10-14T17:57:20.994648Z","end":"2026-10-14T17:57:21.226572Z","steps":["trace[1150155941] 'agreement among raft nodes before linearized reading' (duration: 231.634359ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:27.026566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959} {"level":"info","ts":"2026-10-14T17:57:27.125182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"96.682791ms","hash":846303894,"current-db-size-bytes":54972416,"current-db-size":"55 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T17:57:27.125459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":846303894,"revision":2959,"compact-revision":-1} {"level":"info","ts":"2026-10-14T17:57:34.005638Z","caller":"traceutil/trace.go:171","msg":"trace[1581196740] linearizableReadLoop","detail":"{readStateIndex:5875; appliedIndex:5873; }","duration":"219.369842ms","start":"2026-10-14T17:57:33.786244Z","end":"2026-10-14T17:57:34.005614Z","steps":["trace[1581196740] 'read index received' (duration: 219.109388ms)","trace[1581196740] 'applied index is now lower than readState.Index' (duration: 259.114µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:34.006054Z","caller":"traceutil/trace.go:171","msg":"trace[63883041] transaction","detail":"{read_only:false; response_revision:5661; number_of_response:1; }","duration":"279.719455ms","start":"2026-10-14T17:57:33.726317Z","end":"2026-10-14T17:57:34.006037Z","steps":["trace[63883041] 'process raft request' (duration: 279.054864ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:34.006347Z","caller":"traceutil/trace.go:171","msg":"trace[1232927676] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"275.934275ms","start":"2026-10-14T17:57:33.730402Z","end":"2026-10-14T17:57:34.006337Z","steps":["trace[1232927676] 'process raft request' (duration: 275.149671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:34.006521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.264289ms","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-10-14T17:57:34.006562Z","caller":"traceutil/trace.go:171","msg":"trace[1953046900] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5662; }","duration":"220.379501ms","start":"2026-10-14T17:57:33.786171Z","end":"2026-10-14T17:57:34.006551Z","steps":["trace[1953046900] 'agreement among raft nodes before linearized reading' (duration: 220.294129ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:36.676445Z","caller":"traceutil/trace.go:171","msg":"trace[821192985] linearizableReadLoop","detail":"{readStateIndex:5921; appliedIndex:5920; }","duration":"328.786969ms","start":"2026-10-14T17:57:36.347627Z","end":"2026-10-14T17:57:36.676414Z","steps":["trace[821192985] 'read index received' (duration: 328.506445ms)","trace[821192985] 'applied index is now lower than readState.Index' (duration: 278.804µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:36.676566Z","caller":"traceutil/trace.go:171","msg":"trace[2033490423] transaction","detail":"{read_only:false; response_revision:5706; number_of_response:1; }","duration":"335.199917ms","start":"2026-10-14T17:57:36.34131Z","end":"2026-10-14T17:57:36.67651Z","steps":["trace[2033490423] 'process raft request' (duration: 334.861182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:36.676781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.132065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-14T17:57:36.676829Z","caller":"traceutil/trace.go:171","msg":"trace[973246165] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5706; }","duration":"329.230207ms","start":"2026-10-14T17:57:36.347585Z","end":"2026-10-14T17:57:36.676816Z","steps":["trace[973246165] 'agreement among raft nodes before linearized reading' (duration: 329.010903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:36.676866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:36.347576Z","time spent":"329.281698ms","remote":"127.0.0.1:53830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5675,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-14T17:57:36.676846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:36.341275Z","time spent":"335.416501ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11655,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:57:37.111512Z","caller":"traceutil/trace.go:171","msg":"trace[208974082] transaction","detail":"{read_only:false; response_revision:5707; number_of_response:1; }","duration":"424.660826ms","start":"2026-10-14T17:57:36.686794Z","end":"2026-10-14T17:57:37.111455Z","steps":["trace[208974082] 'process raft request' (duration: 339.770091ms)","trace[208974082] 'compare' (duration: 84.565279ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:57:37.111786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:36.68676Z","time spent":"424.88742ms","remote":"127.0.0.1:55134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:57:37.1291Z","caller":"traceutil/trace.go:171","msg":"trace[988932408] transaction","detail":"{read_only:false; response_revision:5708; number_of_response:1; }","duration":"442.089607ms","start":"2026-10-14T17:57:36.68696Z","end":"2026-10-14T17:57:37.129049Z","steps":["trace[988932408] 'process raft request' (duration: 441.759681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:37.129344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:36.686931Z","time spent":"442.279821ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:57:37.129422Z","caller":"traceutil/trace.go:171","msg":"trace[1126482717] transaction","detail":"{read_only:false; response_revision:5709; number_of_response:1; }","duration":"438.900559ms","start":"2026-10-14T17:57:36.690482Z","end":"2026-10-14T17:57:37.129383Z","steps":["trace[1126482717] 'process raft request' (duration: 438.40155ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:37.129419Z","caller":"traceutil/trace.go:171","msg":"trace[1061890362] transaction","detail":"{read_only:false; response_revision:5710; number_of_response:1; }","duration":"337.934679ms","start":"2026-10-14T17:57:36.791457Z","end":"2026-10-14T17:57:37.129392Z","steps":["trace[1061890362] 'process raft request' (duration: 337.824886ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:37.129777Z","caller":"traceutil/trace.go:171","msg":"trace[2043413759] transaction","detail":"{read_only:false; response_revision:5711; number_of_response:1; }","duration":"337.142013ms","start":"2026-10-14T17:57:36.792612Z","end":"2026-10-14T17:57:37.129754Z","steps":["trace[2043413759] 'process raft request' (duration: 336.733466ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:37.129792Z","caller":"traceutil/trace.go:171","msg":"trace[172269585] linearizableReadLoop","detail":"{readStateIndex:5924; appliedIndex:5921; }","duration":"398.895021ms","start":"2026-10-14T17:57:36.73086Z","end":"2026-10-14T17:57:37.129755Z","steps":["trace[172269585] 'read index received' (duration: 295.851861ms)","trace[172269585] 'applied index is now lower than readState.Index' (duration: 103.04201ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:37.129854Z","caller":"traceutil/trace.go:171","msg":"trace[547439759] transaction","detail":"{read_only:false; response_revision:5712; number_of_response:1; }","duration":"113.3825ms","start":"2026-10-14T17:57:37.016434Z","end":"2026-10-14T17:57:37.129817Z","steps":["trace[547439759] 'process raft request' (duration: 113.142335ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:37.129993Z","caller":"traceutil/trace.go:171","msg":"trace[1615208301] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"113.465101ms","start":"2026-10-14T17:57:37.016509Z","end":"2026-10-14T17:57:37.129974Z","steps":["trace[1615208301] 'process raft request' (duration: 113.195486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:37.130076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:36.792605Z","time spent":"337.219065ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:57:37.130142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.072616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j4m5g\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-14T17:57:37.130195Z","caller":"traceutil/trace.go:171","msg":"trace[647679906] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j4m5g; range_end:; response_count:1; response_revision:5713; }","duration":"200.184818ms","start":"2026-10-14T17:57:36.929998Z","end":"2026-10-14T17:57:37.130182Z","steps":["trace[647679906] 'agreement among raft nodes before linearized reading' (duration: 199.995105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:37.130316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.43296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-w8szh\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-10-14T17:57:37.130389Z","caller":"traceutil/trace.go:171","msg":"trace[2009321769] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-w8szh; range_end:; response_count:1; response_revision:5713; }","duration":"399.569023ms","start":"2026-10-14T17:57:36.730797Z","end":"2026-10-14T17:57:37.130366Z","steps":["trace[2009321769] 'agreement among raft nodes before linearized reading' (duration: 399.309118ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:37.130448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:36.730766Z","time spent":"399.661415ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7842,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-w8szh\" "} {"level":"warn","ts":"2026-10-14T17:57:37.129568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:36.690461Z","time spent":"439.042552ms","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:57:37.131166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:36.791429Z","time spent":"338.219633ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:57:45.064532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.786154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:57:45.064648Z","caller":"traceutil/trace.go:171","msg":"trace[1698316618] linearizableReadLoop","detail":"{readStateIndex:6101; appliedIndex:6100; }","duration":"443.463031ms","start":"2026-10-14T17:57:44.621168Z","end":"2026-10-14T17:57:45.064631Z","steps":["trace[1698316618] 'read index received' (duration: 79.513914ms)","trace[1698316618] 'applied index is now lower than readState.Index' (duration: 363.947667ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:45.064727Z","caller":"traceutil/trace.go:171","msg":"trace[910955086] transaction","detail":"{read_only:false; response_revision:5882; number_of_response:1; }","duration":"453.340435ms","start":"2026-10-14T17:57:44.611375Z","end":"2026-10-14T17:57:45.064715Z","steps":["trace[910955086] 'process raft request' (duration: 89.296606ms)","trace[910955086] 'compare' (duration: 363.652302ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:57:45.064788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:44.61135Z","time spent":"453.415126ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T17:57:45.065017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.013557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:57:45.065124Z","caller":"traceutil/trace.go:171","msg":"trace[2004239938] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5882; }","duration":"295.225741ms","start":"2026-10-14T17:57:44.769874Z","end":"2026-10-14T17:57:45.0651Z","steps":["trace[2004239938] 'agreement among raft nodes before linearized reading' (duration: 294.954376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:45.065162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.714635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-chunks-cache-0\" ","response":"range_response_count:1 size:4464"} {"level":"info","ts":"2026-10-14T17:57:45.06527Z","caller":"traceutil/trace.go:171","msg":"trace[1502550840] range","detail":"{range_begin:/registry/pods/monitoring/loki-chunks-cache-0; range_end:; response_count:1; response_revision:5882; }","duration":"228.884828ms","start":"2026-10-14T17:57:44.836363Z","end":"2026-10-14T17:57:45.065248Z","steps":["trace[1502550840] 'agreement among raft nodes before linearized reading' (duration: 228.48454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:45.065313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.103694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2dd3f307-67ea-4ba9-b619-7ae3f55eba71\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-10-14T17:57:45.065384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.349806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-14T17:57:45.065441Z","caller":"traceutil/trace.go:171","msg":"trace[874359158] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5882; }","duration":"172.452558ms","start":"2026-10-14T17:57:44.892959Z","end":"2026-10-14T17:57:45.065412Z","steps":["trace[874359158] 'agreement among raft nodes before linearized reading' (duration: 171.882117ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.065439Z","caller":"traceutil/trace.go:171","msg":"trace[228051293] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2dd3f307-67ea-4ba9-b619-7ae3f55eba71; range_end:; response_count:1; response_revision:5882; }","duration":"444.293468ms","start":"2026-10-14T17:57:44.621105Z","end":"2026-10-14T17:57:45.065398Z","steps":["trace[228051293] 'agreement among raft nodes before linearized reading' (duration: 443.817869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:45.065489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:44.621081Z","time spent":"444.397549ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2dd3f307-67ea-4ba9-b619-7ae3f55eba71\" "} {"level":"info","ts":"2026-10-14T17:57:45.241492Z","caller":"traceutil/trace.go:171","msg":"trace[871249335] transaction","detail":"{read_only:false; response_revision:5883; number_of_response:1; }","duration":"166.561729ms","start":"2026-10-14T17:57:45.074892Z","end":"2026-10-14T17:57:45.241454Z","steps":["trace[871249335] 'process raft request' (duration: 143.204188ms)","trace[871249335] 'compare' (duration: 23.178878ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:45.301209Z","caller":"traceutil/trace.go:171","msg":"trace[894331293] transaction","detail":"{read_only:false; response_revision:5884; number_of_response:1; }","duration":"138.34272ms","start":"2026-10-14T17:57:45.162838Z","end":"2026-10-14T17:57:45.301181Z","steps":["trace[894331293] 'process raft request' (duration: 138.118006ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.301512Z","caller":"traceutil/trace.go:171","msg":"trace[544605104] transaction","detail":"{read_only:false; response_revision:5885; number_of_response:1; }","duration":"135.031749ms","start":"2026-10-14T17:57:45.16645Z","end":"2026-10-14T17:57:45.301482Z","steps":["trace[544605104] 'process raft request' (duration: 134.690652ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.301874Z","caller":"traceutil/trace.go:171","msg":"trace[1108175152] transaction","detail":"{read_only:false; response_revision:5886; number_of_response:1; }","duration":"135.236982ms","start":"2026-10-14T17:57:45.166623Z","end":"2026-10-14T17:57:45.30186Z","steps":["trace[1108175152] 'process raft request' (duration: 134.723133ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.301838Z","caller":"traceutil/trace.go:171","msg":"trace[392824858] linearizableReadLoop","detail":"{readStateIndex:6106; appliedIndex:6101; }","duration":"125.131106ms","start":"2026-10-14T17:57:45.176678Z","end":"2026-10-14T17:57:45.301809Z","steps":["trace[392824858] 'read index received' (duration: 41.352622ms)","trace[392824858] 'applied index is now lower than readState.Index' (duration: 83.777174ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:45.302014Z","caller":"traceutil/trace.go:171","msg":"trace[2079918279] transaction","detail":"{read_only:false; response_revision:5887; number_of_response:1; }","duration":"134.385976ms","start":"2026-10-14T17:57:45.167611Z","end":"2026-10-14T17:57:45.301997Z","steps":["trace[2079918279] 'process raft request' (duration: 134.124001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:45.302209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.511023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T17:57:45.302258Z","caller":"traceutil/trace.go:171","msg":"trace[1046465882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5887; }","duration":"125.624075ms","start":"2026-10-14T17:57:45.176622Z","end":"2026-10-14T17:57:45.302246Z","steps":["trace[1046465882] 'agreement among raft nodes before linearized reading' (duration: 125.446802ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.490536Z","caller":"traceutil/trace.go:171","msg":"trace[1448783510] transaction","detail":"{read_only:false; response_revision:5890; number_of_response:1; }","duration":"242.145572ms","start":"2026-10-14T17:57:45.248374Z","end":"2026-10-14T17:57:45.490519Z","steps":["trace[1448783510] 'process raft request' (duration: 242.05659ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.490515Z","caller":"traceutil/trace.go:171","msg":"trace[1857487427] transaction","detail":"{read_only:false; response_revision:5888; number_of_response:1; }","duration":"243.000348ms","start":"2026-10-14T17:57:45.247472Z","end":"2026-10-14T17:57:45.490473Z","steps":["trace[1857487427] 'process raft request' (duration: 232.556606ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.490635Z","caller":"traceutil/trace.go:171","msg":"trace[15696533] transaction","detail":"{read_only:false; response_revision:5889; number_of_response:1; }","duration":"242.619751ms","start":"2026-10-14T17:57:45.247988Z","end":"2026-10-14T17:57:45.490608Z","steps":["trace[15696533] 'process raft request' (duration: 242.344945ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.490823Z","caller":"traceutil/trace.go:171","msg":"trace[1475076115] transaction","detail":"{read_only:false; response_revision:5891; number_of_response:1; }","duration":"242.355826ms","start":"2026-10-14T17:57:45.248443Z","end":"2026-10-14T17:57:45.490799Z","steps":["trace[1475076115] 'process raft request' (duration: 242.03492ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.490886Z","caller":"traceutil/trace.go:171","msg":"trace[154203074] transaction","detail":"{read_only:false; response_revision:5892; number_of_response:1; }","duration":"241.612272ms","start":"2026-10-14T17:57:45.249255Z","end":"2026-10-14T17:57:45.490867Z","steps":["trace[154203074] 'process raft request' (duration: 241.431629ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:45.490928Z","caller":"traceutil/trace.go:171","msg":"trace[490651778] linearizableReadLoop","detail":"{readStateIndex:6112; appliedIndex:6106; }","duration":"188.919252ms","start":"2026-10-14T17:57:45.30195Z","end":"2026-10-14T17:57:45.490869Z","steps":["trace[490651778] 'read index received' (duration: 178.099142ms)","trace[490651778] 'applied index is now lower than readState.Index' (duration: 10.819129ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:45.491088Z","caller":"traceutil/trace.go:171","msg":"trace[115170850] transaction","detail":"{read_only:false; response_revision:5893; number_of_response:1; }","duration":"238.707429ms","start":"2026-10-14T17:57:45.252345Z","end":"2026-10-14T17:57:45.491053Z","steps":["trace[115170850] 'process raft request' (duration: 238.456694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:45.491183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.276444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j4m5g\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-14T17:57:45.491206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.69025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2dd3f307-67ea-4ba9-b619-7ae3f55eba71\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-14T17:57:45.491222Z","caller":"traceutil/trace.go:171","msg":"trace[254603755] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j4m5g; range_end:; response_count:1; response_revision:5893; }","duration":"313.383865ms","start":"2026-10-14T17:57:45.177825Z","end":"2026-10-14T17:57:45.491209Z","steps":["trace[254603755] 'agreement among raft nodes before linearized reading' (duration: 313.181701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:45.491226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.151643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:57:45.491255Z","caller":"traceutil/trace.go:171","msg":"trace[271786364] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2dd3f307-67ea-4ba9-b619-7ae3f55eba71; range_end:; response_count:1; response_revision:5893; }","duration":"244.803551ms","start":"2026-10-14T17:57:45.246436Z","end":"2026-10-14T17:57:45.491239Z","steps":["trace[271786364] 'agreement among raft nodes before linearized reading' (duration: 244.606178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:45.491262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:45.1778Z","time spent":"313.454017ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-j4m5g\" "} {"level":"info","ts":"2026-10-14T17:57:45.491283Z","caller":"traceutil/trace.go:171","msg":"trace[769812748] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:5893; }","duration":"306.243964ms","start":"2026-10-14T17:57:45.185017Z","end":"2026-10-14T17:57:45.491261Z","steps":["trace[769812748] 'agreement among raft nodes before linearized reading' (duration: 306.133432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:57:45.491322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:57:45.185006Z","time spent":"306.305885ms","remote":"127.0.0.1:38588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"info","ts":"2026-10-14T17:57:45.512206Z","caller":"traceutil/trace.go:171","msg":"trace[1283612184] transaction","detail":"{read_only:false; response_revision:5894; number_of_response:1; }","duration":"205.737951ms","start":"2026-10-14T17:57:45.306439Z","end":"2026-10-14T17:57:45.512177Z","steps":["trace[1283612184] 'process raft request' (duration: 205.502496ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:27.034321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5575} {"level":"info","ts":"2026-10-14T18:02:27.125565Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5575,"took":"88.845145ms","hash":3606770457,"current-db-size-bytes":54972416,"current-db-size":"55 MB","current-db-size-in-use-bytes":28160000,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:02:27.125656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3606770457,"revision":5575,"compact-revision":2959} {"level":"warn","ts":"2026-10-14T18:04:06.07133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.535108ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-14T18:04:06.071478Z","caller":"traceutil/trace.go:171","msg":"trace[375854824] linearizableReadLoop","detail":"{readStateIndex:9080; appliedIndex:9079; }","duration":"335.800177ms","start":"2026-10-14T18:04:05.735653Z","end":"2026-10-14T18:04:06.071453Z","steps":["trace[375854824] 'read index received' (duration: 178.064775ms)","trace[375854824] 'applied index is now lower than readState.Index' (duration: 157.733372ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:06.071892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.971805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-14T18:04:06.071834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.697746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-14T18:04:06.071915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.117135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T18:04:06.071942Z","caller":"traceutil/trace.go:171","msg":"trace[1401216281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8771; }","duration":"153.121357ms","start":"2026-10-14T18:04:05.918806Z","end":"2026-10-14T18:04:06.071927Z","steps":["trace[1401216281] 'agreement among raft nodes before linearized reading' (duration: 152.899533ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:06.071967Z","caller":"traceutil/trace.go:171","msg":"trace[163496006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8771; }","duration":"161.876419ms","start":"2026-10-14T18:04:05.910066Z","end":"2026-10-14T18:04:06.071943Z","steps":["trace[163496006] 'agreement among raft nodes before linearized reading' (duration: 161.558723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:06.071898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.003039ms","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-10-14T18:04:06.072177Z","caller":"traceutil/trace.go:171","msg":"trace[811826337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8771; }","duration":"144.368886ms","start":"2026-10-14T18:04:05.927786Z","end":"2026-10-14T18:04:06.072155Z","steps":["trace[811826337] 'agreement among raft nodes before linearized reading' (duration: 143.837617ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:06.071996Z","caller":"traceutil/trace.go:171","msg":"trace[604652688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8771; }","duration":"154.237287ms","start":"2026-10-14T18:04:05.917728Z","end":"2026-10-14T18:04:06.071965Z","steps":["trace[604652688] 'agreement among raft nodes before linearized reading' (duration: 154.015113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:06.072099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.189694ms","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-10-14T18:04:06.072779Z","caller":"traceutil/trace.go:171","msg":"trace[678249959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8771; }","duration":"337.0765ms","start":"2026-10-14T18:04:05.73558Z","end":"2026-10-14T18:04:06.072657Z","steps":["trace[678249959] 'agreement among raft nodes before linearized reading' (duration: 335.94289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:06.072913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:05.735553Z","time spent":"337.338475ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T18:04:17.395047Z","caller":"traceutil/trace.go:171","msg":"trace[1509834635] linearizableReadLoop","detail":"{readStateIndex:9141; appliedIndex:9140; }","duration":"307.045747ms","start":"2026-10-14T18:04:17.087963Z","end":"2026-10-14T18:04:17.395008Z","steps":["trace[1509834635] 'read index received' (duration: 306.951495ms)","trace[1509834635] 'applied index is now lower than readState.Index' (duration: 92.912µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:17.395106Z","caller":"traceutil/trace.go:171","msg":"trace[1738083244] transaction","detail":"{read_only:false; response_revision:8830; number_of_response:1; }","duration":"319.65343ms","start":"2026-10-14T18:04:17.0754Z","end":"2026-10-14T18:04:17.395053Z","steps":["trace[1738083244] 'process raft request' (duration: 319.421166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:17.395192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.213351ms","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-10-14T18:04:17.395229Z","caller":"traceutil/trace.go:171","msg":"trace[907811288] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8830; }","duration":"307.329773ms","start":"2026-10-14T18:04:17.087889Z","end":"2026-10-14T18:04:17.395218Z","steps":["trace[907811288] 'agreement among raft nodes before linearized reading' (duration: 307.237151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:17.395263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:17.087836Z","time spent":"307.418115ms","remote":"127.0.0.1:51508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:04:17.395316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:17.075343Z","time spent":"319.861584ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:17.463654Z","caller":"traceutil/trace.go:171","msg":"trace[1334623367] transaction","detail":"{read_only:false; response_revision:8832; number_of_response:1; }","duration":"335.816067ms","start":"2026-10-14T18:04:17.12779Z","end":"2026-10-14T18:04:17.463606Z","steps":["trace[1334623367] 'process raft request' (duration: 335.705175ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:17.463733Z","caller":"traceutil/trace.go:171","msg":"trace[815465112] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"362.847005ms","start":"2026-10-14T18:04:17.100815Z","end":"2026-10-14T18:04:17.463662Z","steps":["trace[815465112] 'process raft request' (duration: 362.436948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:17.463893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:17.127756Z","time spent":"335.993761ms","remote":"127.0.0.1:55226","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-10-14T18:04:17.463991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:17.100795Z","time spent":"363.040988ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:17.463961Z","caller":"traceutil/trace.go:171","msg":"trace[1705066126] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"284.529614ms","start":"2026-10-14T18:04:17.1794Z","end":"2026-10-14T18:04:17.46393Z","steps":["trace[1705066126] 'process raft request' (duration: 284.156757ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:18.385168Z","caller":"traceutil/trace.go:171","msg":"trace[515012344] linearizableReadLoop","detail":"{readStateIndex:9145; appliedIndex:9144; }","duration":"226.033098ms","start":"2026-10-14T18:04:18.159108Z","end":"2026-10-14T18:04:18.385141Z","steps":["trace[515012344] 'read index received' (duration: 225.701182ms)","trace[515012344] 'applied index is now lower than readState.Index' (duration: 330.336µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:18.385238Z","caller":"traceutil/trace.go:171","msg":"trace[1376568505] transaction","detail":"{read_only:false; response_revision:8834; number_of_response:1; }","duration":"229.547653ms","start":"2026-10-14T18:04:18.155647Z","end":"2026-10-14T18:04:18.385195Z","steps":["trace[1376568505] 'process raft request' (duration: 229.302358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:18.385482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.11413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j4m5g\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-14T18:04:18.385484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.353279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-14T18:04:18.385531Z","caller":"traceutil/trace.go:171","msg":"trace[1855305228] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j4m5g; range_end:; response_count:1; response_revision:8834; }","duration":"207.200732ms","start":"2026-10-14T18:04:18.178317Z","end":"2026-10-14T18:04:18.385518Z","steps":["trace[1855305228] 'agreement among raft nodes before linearized reading' (duration: 206.999868ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:18.385538Z","caller":"traceutil/trace.go:171","msg":"trace[1257374749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8834; }","duration":"204.441301ms","start":"2026-10-14T18:04:18.181085Z","end":"2026-10-14T18:04:18.385526Z","steps":["trace[1257374749] 'agreement among raft nodes before linearized reading' (duration: 204.281198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:18.385519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.373724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T18:04:18.385609Z","caller":"traceutil/trace.go:171","msg":"trace[414221071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8834; }","duration":"226.505117ms","start":"2026-10-14T18:04:18.159085Z","end":"2026-10-14T18:04:18.38559Z","steps":["trace[414221071] 'agreement among raft nodes before linearized reading' (duration: 226.200372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:18.385686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.1186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T18:04:18.385747Z","caller":"traceutil/trace.go:171","msg":"trace[576544065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8834; }","duration":"207.258563ms","start":"2026-10-14T18:04:18.178474Z","end":"2026-10-14T18:04:18.385733Z","steps":["trace[576544065] 'agreement among raft nodes before linearized reading' (duration: 206.989988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:18.385751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.207293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-14T18:04:18.385892Z","caller":"traceutil/trace.go:171","msg":"trace[1097862505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8834; }","duration":"206.379866ms","start":"2026-10-14T18:04:18.179467Z","end":"2026-10-14T18:04:18.385847Z","steps":["trace[1097862505] 'agreement among raft nodes before linearized reading' (duration: 205.967148ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:18.49474Z","caller":"traceutil/trace.go:171","msg":"trace[384201503] transaction","detail":"{read_only:false; response_revision:8837; number_of_response:1; }","duration":"103.612436ms","start":"2026-10-14T18:04:18.391092Z","end":"2026-10-14T18:04:18.494705Z","steps":["trace[384201503] 'process raft request' (duration: 103.507234ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:18.494956Z","caller":"traceutil/trace.go:171","msg":"trace[601566101] linearizableReadLoop","detail":"{readStateIndex:9149; appliedIndex:9145; }","duration":"103.041675ms","start":"2026-10-14T18:04:18.391875Z","end":"2026-10-14T18:04:18.494916Z","steps":["trace[601566101] 'read index received' (duration: 11.40179ms)","trace[601566101] 'applied index is now lower than readState.Index' (duration: 91.638165ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:18.495117Z","caller":"traceutil/trace.go:171","msg":"trace[652361656] transaction","detail":"{read_only:false; response_revision:8836; number_of_response:1; }","duration":"104.032254ms","start":"2026-10-14T18:04:18.391067Z","end":"2026-10-14T18:04:18.495099Z","steps":["trace[652361656] 'process raft request' (duration: 99.850606ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:18.495189Z","caller":"traceutil/trace.go:171","msg":"trace[2087917459] transaction","detail":"{read_only:false; response_revision:8838; number_of_response:1; }","duration":"104.042724ms","start":"2026-10-14T18:04:18.39111Z","end":"2026-10-14T18:04:18.495153Z","steps":["trace[2087917459] 'process raft request' (duration: 103.551084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:18.49581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.923202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224174"} {"level":"info","ts":"2026-10-14T18:04:18.495924Z","caller":"traceutil/trace.go:171","msg":"trace[1739599954] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8838; }","duration":"104.081705ms","start":"2026-10-14T18:04:18.39182Z","end":"2026-10-14T18:04:18.495902Z","steps":["trace[1739599954] 'agreement among raft nodes before linearized reading' (duration: 103.28852ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:46.654921Z","caller":"traceutil/trace.go:171","msg":"trace[1970869666] transaction","detail":"{read_only:false; response_revision:9040; number_of_response:1; }","duration":"112.960767ms","start":"2026-10-14T18:04:46.541931Z","end":"2026-10-14T18:04:46.654892Z","steps":["trace[1970869666] 'process raft request' (duration: 112.788424ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:46.658583Z","caller":"traceutil/trace.go:171","msg":"trace[1559634857] transaction","detail":"{read_only:false; response_revision:9041; number_of_response:1; }","duration":"112.271215ms","start":"2026-10-14T18:04:46.546277Z","end":"2026-10-14T18:04:46.658548Z","steps":["trace[1559634857] 'process raft request' (duration: 112.105262ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:48.877744Z","caller":"traceutil/trace.go:171","msg":"trace[541149665] transaction","detail":"{read_only:false; response_revision:9049; number_of_response:1; }","duration":"209.29648ms","start":"2026-10-14T18:04:48.668423Z","end":"2026-10-14T18:04:48.877719Z","steps":["trace[541149665] 'process raft request' (duration: 209.143757ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:48.900916Z","caller":"traceutil/trace.go:171","msg":"trace[712745511] linearizableReadLoop","detail":"{readStateIndex:9370; appliedIndex:9368; }","duration":"229.329418ms","start":"2026-10-14T18:04:48.671564Z","end":"2026-10-14T18:04:48.900894Z","steps":["trace[712745511] 'read index received' (duration: 206.04313ms)","trace[712745511] 'applied index is now lower than readState.Index' (duration: 23.285148ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:48.901025Z","caller":"traceutil/trace.go:171","msg":"trace[2101955901] transaction","detail":"{read_only:false; response_revision:9050; number_of_response:1; }","duration":"230.003581ms","start":"2026-10-14T18:04:48.671009Z","end":"2026-10-14T18:04:48.901013Z","steps":["trace[2101955901] 'process raft request' (duration: 229.729576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:48.901201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.468649ms","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-10-14T18:04:48.901261Z","caller":"traceutil/trace.go:171","msg":"trace[1465159005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9050; }","duration":"224.581942ms","start":"2026-10-14T18:04:48.676664Z","end":"2026-10-14T18:04:48.901246Z","steps":["trace[1465159005] 'agreement among raft nodes before linearized reading' (duration: 224.394278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:48.901268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.162785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j4m5g\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-14T18:04:48.901318Z","caller":"traceutil/trace.go:171","msg":"trace[405785279] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j4m5g; range_end:; response_count:1; response_revision:9050; }","duration":"198.246637ms","start":"2026-10-14T18:04:48.703058Z","end":"2026-10-14T18:04:48.901305Z","steps":["trace[405785279] 'agreement among raft nodes before linearized reading' (duration: 198.082304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:48.901427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.860098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-14T18:04:48.90144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.004456ms","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-10-14T18:04:48.901479Z","caller":"traceutil/trace.go:171","msg":"trace[1454803558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9050; }","duration":"229.96375ms","start":"2026-10-14T18:04:48.671503Z","end":"2026-10-14T18:04:48.901466Z","steps":["trace[1454803558] 'agreement among raft nodes before linearized reading' (duration: 229.791417ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:48.901516Z","caller":"traceutil/trace.go:171","msg":"trace[150981318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9050; }","duration":"209.124497ms","start":"2026-10-14T18:04:48.692373Z","end":"2026-10-14T18:04:48.901497Z","steps":["trace[150981318] 'agreement among raft nodes before linearized reading' (duration: 208.831112ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:50.813957Z","caller":"traceutil/trace.go:171","msg":"trace[361473407] transaction","detail":"{read_only:false; response_revision:9060; number_of_response:1; }","duration":"150.591471ms","start":"2026-10-14T18:04:50.663336Z","end":"2026-10-14T18:04:50.813927Z","steps":["trace[361473407] 'process raft request' (duration: 53.28232ms)","trace[361473407] 'compare' (duration: 96.776061ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:57.900288Z","caller":"traceutil/trace.go:171","msg":"trace[206389234] linearizableReadLoop","detail":"{readStateIndex:9423; appliedIndex:9420; }","duration":"135.858669ms","start":"2026-10-14T18:04:57.764411Z","end":"2026-10-14T18:04:57.90027Z","steps":["trace[206389234] 'read index received' (duration: 98.810408ms)","trace[206389234] 'applied index is now lower than readState.Index' (duration: 37.047441ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:57.900296Z","caller":"traceutil/trace.go:171","msg":"trace[566388900] transaction","detail":"{read_only:false; response_revision:9099; number_of_response:1; }","duration":"199.270865ms","start":"2026-10-14T18:04:57.700986Z","end":"2026-10-14T18:04:57.900257Z","steps":["trace[566388900] 'process raft request' (duration: 162.264605ms)","trace[566388900] 'compare' (duration: 36.829587ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:57.900353Z","caller":"traceutil/trace.go:171","msg":"trace[1435074822] transaction","detail":"{read_only:false; response_revision:9101; number_of_response:1; }","duration":"157.570769ms","start":"2026-10-14T18:04:57.742748Z","end":"2026-10-14T18:04:57.900318Z","steps":["trace[1435074822] 'process raft request' (duration: 157.490267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:57.900392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.966191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:04:57.900418Z","caller":"traceutil/trace.go:171","msg":"trace[487082219] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:9101; }","duration":"136.026532ms","start":"2026-10-14T18:04:57.764384Z","end":"2026-10-14T18:04:57.900411Z","steps":["trace[487082219] 'agreement among raft nodes before linearized reading' (duration: 135.956811ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:57.900379Z","caller":"traceutil/trace.go:171","msg":"trace[387784423] transaction","detail":"{read_only:false; response_revision:9100; number_of_response:1; }","duration":"167.529022ms","start":"2026-10-14T18:04:57.732788Z","end":"2026-10-14T18:04:57.900317Z","steps":["trace[387784423] 'process raft request' (duration: 167.413409ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:05:01.940341Z","caller":"traceutil/trace.go:171","msg":"trace[2068214897] linearizableReadLoop","detail":"{readStateIndex:9453; appliedIndex:9451; }","duration":"334.533853ms","start":"2026-10-14T18:05:01.605781Z","end":"2026-10-14T18:05:01.940314Z","steps":["trace[2068214897] 'read index received' (duration: 25.629961ms)","trace[2068214897] 'applied index is now lower than readState.Index' (duration: 308.902712ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:05:01.940778Z","caller":"traceutil/trace.go:171","msg":"trace[834895498] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9130; }","duration":"399.238963ms","start":"2026-10-14T18:05:01.541519Z","end":"2026-10-14T18:05:01.940758Z","steps":["trace[834895498] 'process raft request' (duration: 368.943976ms)","trace[834895498] 'compare' (duration: 28.458494ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:05:01.941232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:05:01.541501Z","time spent":"399.364106ms","remote":"127.0.0.1:53064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-10-14T18:05:01.941696Z","caller":"traceutil/trace.go:171","msg":"trace[286787159] transaction","detail":"{read_only:false; response_revision:9131; number_of_response:1; }","duration":"321.19482ms","start":"2026-10-14T18:05:01.620487Z","end":"2026-10-14T18:05:01.941682Z","steps":["trace[286787159] 'process raft request' (duration: 319.749133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:05:01.94177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:05:01.620465Z","time spent":"321.266331ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:05:01.942884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.093381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:05:01.942941Z","caller":"traceutil/trace.go:171","msg":"trace[1951945761] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:9131; }","duration":"337.199962ms","start":"2026-10-14T18:05:01.605729Z","end":"2026-10-14T18:05:01.942929Z","steps":["trace[1951945761] 'agreement among raft nodes before linearized reading' (duration: 337.07623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:05:01.942974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:05:01.605704Z","time spent":"337.260394ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:05:02.067015Z","caller":"traceutil/trace.go:171","msg":"trace[582474284] transaction","detail":"{read_only:false; response_revision:9132; number_of_response:1; }","duration":"184.485543ms","start":"2026-10-14T18:05:01.882506Z","end":"2026-10-14T18:05:02.066991Z","steps":["trace[582474284] 'process raft request' (duration: 178.499083ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:05:02.070762Z","caller":"traceutil/trace.go:171","msg":"trace[197929039] transaction","detail":"{read_only:false; response_revision:9133; number_of_response:1; }","duration":"154.325809ms","start":"2026-10-14T18:05:01.916416Z","end":"2026-10-14T18:05:02.070742Z","steps":["trace[197929039] 'process raft request' (duration: 154.020153ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:05:02.070811Z","caller":"traceutil/trace.go:171","msg":"trace[1075902009] transaction","detail":"{read_only:false; response_revision:9134; number_of_response:1; }","duration":"149.164764ms","start":"2026-10-14T18:05:01.921616Z","end":"2026-10-14T18:05:02.070781Z","steps":["trace[1075902009] 'process raft request' (duration: 149.033602ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:05:02.070948Z","caller":"traceutil/trace.go:171","msg":"trace[89457324] linearizableReadLoop","detail":"{readStateIndex:9458; appliedIndex:9454; }","duration":"130.516371ms","start":"2026-10-14T18:05:01.94042Z","end":"2026-10-14T18:05:02.070936Z","steps":["trace[89457324] 'read index received' (duration: 120.597868ms)","trace[89457324] 'applied index is now lower than readState.Index' (duration: 9.917843ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:05:02.071106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.538321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-pvb42\" ","response":"range_response_count:1 size:4642"} {"level":"info","ts":"2026-10-14T18:05:02.071084Z","caller":"traceutil/trace.go:171","msg":"trace[607040655] transaction","detail":"{read_only:false; response_revision:9135; number_of_response:1; }","duration":"147.25307ms","start":"2026-10-14T18:05:01.923804Z","end":"2026-10-14T18:05:02.071057Z","steps":["trace[607040655] 'process raft request' (duration: 146.912243ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:05:02.07114Z","caller":"traceutil/trace.go:171","msg":"trace[225152629] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-pvb42; range_end:; response_count:1; response_revision:9135; }","duration":"436.625533ms","start":"2026-10-14T18:05:01.634506Z","end":"2026-10-14T18:05:02.071132Z","steps":["trace[225152629] 'agreement among raft nodes before linearized reading' (duration: 436.47188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:05:02.071168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:05:01.634483Z","time spent":"436.677393ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4666,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-pvb42\" "} {"level":"warn","ts":"2026-10-14T18:05:02.071316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.727188ms","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-10-14T18:05:02.071378Z","caller":"traceutil/trace.go:171","msg":"trace[243031410] 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:9135; }","duration":"277.85366ms","start":"2026-10-14T18:05:01.793508Z","end":"2026-10-14T18:05:02.071362Z","steps":["trace[243031410] 'agreement among raft nodes before linearized reading' (duration: 277.742808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:05:02.07139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.105798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-pvb42\" ","response":"range_response_count:1 size:4642"} {"level":"info","ts":"2026-10-14T18:05:02.071426Z","caller":"traceutil/trace.go:171","msg":"trace[283703889] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-pvb42; range_end:; response_count:1; response_revision:9135; }","duration":"189.21641ms","start":"2026-10-14T18:05:01.882199Z","end":"2026-10-14T18:05:02.071415Z","steps":["trace[283703889] 'agreement among raft nodes before linearized reading' (duration: 189.049057ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:05:09.131056Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000254e.wal"} {"level":"info","ts":"2026-10-14T18:06:00.223909Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:06:00.23316Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T18:06:00.234038Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-14T18:06:29.742351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.11176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:06:29.742499Z","caller":"traceutil/trace.go:171","msg":"trace[1821866125] linearizableReadLoop","detail":"{readStateIndex:10315; appliedIndex:10314; }","duration":"182.310545ms","start":"2026-10-14T18:06:29.560162Z","end":"2026-10-14T18:06:29.742473Z","steps":["trace[1821866125] 'read index received' (duration: 17.90275ms)","trace[1821866125] 'applied index is now lower than readState.Index' (duration: 164.405394ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:06:29.742613Z","caller":"traceutil/trace.go:171","msg":"trace[1944323168] transaction","detail":"{read_only:false; response_revision:9969; number_of_response:1; }","duration":"201.020879ms","start":"2026-10-14T18:06:29.541574Z","end":"2026-10-14T18:06:29.742595Z","steps":["trace[1944323168] 'process raft request' (duration: 36.539362ms)","trace[1944323168] 'compare' (duration: 164.000238ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:06:29.742914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.744333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T18:06:29.742984Z","caller":"traceutil/trace.go:171","msg":"trace[96465881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9969; }","duration":"182.850635ms","start":"2026-10-14T18:06:29.560116Z","end":"2026-10-14T18:06:29.742966Z","steps":["trace[96465881] 'agreement among raft nodes before linearized reading' (duration: 182.631851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:06:29.743004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.889721ms","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-10-14T18:06:29.743023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.032795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T18:06:29.743102Z","caller":"traceutil/trace.go:171","msg":"trace[731381592] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9969; }","duration":"136.036034ms","start":"2026-10-14T18:06:29.607041Z","end":"2026-10-14T18:06:29.743077Z","steps":["trace[731381592] 'agreement among raft nodes before linearized reading' (duration: 135.701888ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:06:29.743103Z","caller":"traceutil/trace.go:171","msg":"trace[610078684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9969; }","duration":"128.142997ms","start":"2026-10-14T18:06:29.614941Z","end":"2026-10-14T18:06:29.743084Z","steps":["trace[610078684] 'agreement among raft nodes before linearized reading' (duration: 127.78827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:06:29.742909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.253443ms","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-10-14T18:06:29.74327Z","caller":"traceutil/trace.go:171","msg":"trace[242776455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9969; }","duration":"157.638961ms","start":"2026-10-14T18:06:29.585609Z","end":"2026-10-14T18:06:29.743248Z","steps":["trace[242776455] 'agreement among raft nodes before linearized reading' (duration: 157.092841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:06:30.017253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.202838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:06:30.017571Z","caller":"traceutil/trace.go:171","msg":"trace[1469223245] linearizableReadLoop","detail":"{readStateIndex:10318; appliedIndex:10315; }","duration":"268.50625ms","start":"2026-10-14T18:06:29.74904Z","end":"2026-10-14T18:06:30.017547Z","steps":["trace[1469223245] 'read index received' (duration: 87.434788ms)","trace[1469223245] 'applied index is now lower than readState.Index' (duration: 181.070212ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:06:30.017705Z","caller":"traceutil/trace.go:171","msg":"trace[2035357185] transaction","detail":"{read_only:false; response_revision:9972; number_of_response:1; }","duration":"269.445878ms","start":"2026-10-14T18:06:29.748247Z","end":"2026-10-14T18:06:30.017693Z","steps":["trace[2035357185] 'process raft request' (duration: 269.147122ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:06:30.017821Z","caller":"traceutil/trace.go:171","msg":"trace[357058384] transaction","detail":"{read_only:false; response_revision:9971; number_of_response:1; }","duration":"269.59056ms","start":"2026-10-14T18:06:29.748191Z","end":"2026-10-14T18:06:30.017782Z","steps":["trace[357058384] 'process raft request' (duration: 127.692469ms)","trace[357058384] 'compare' (duration: 140.869212ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:06:30.018041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.572496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-14T18:06:30.018043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.970228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20093"} {"level":"info","ts":"2026-10-14T18:06:30.018124Z","caller":"traceutil/trace.go:171","msg":"trace[361874612] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9972; }","duration":"184.727159ms","start":"2026-10-14T18:06:29.833373Z","end":"2026-10-14T18:06:30.0181Z","steps":["trace[361874612] 'agreement among raft nodes before linearized reading' (duration: 184.408523ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:06:30.018132Z","caller":"traceutil/trace.go:171","msg":"trace[390387538] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9972; }","duration":"269.096721ms","start":"2026-10-14T18:06:29.749017Z","end":"2026-10-14T18:06:30.018113Z","steps":["trace[390387538] 'agreement among raft nodes before linearized reading' (duration: 268.804316ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:07:27.050661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7972} {"level":"info","ts":"2026-10-14T18:07:27.14074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7972,"took":"87.73077ms","hash":3313116960,"current-db-size-bytes":54972416,"current-db-size":"55 MB","current-db-size-in-use-bytes":25079808,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-14T18:07:27.140837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3313116960,"revision":7972,"compact-revision":5575} {"level":"info","ts":"2026-10-14T18:12:27.058834Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10386} {"level":"info","ts":"2026-10-14T18:12:27.147138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10386,"took":"86.069786ms","hash":3248937966,"current-db-size-bytes":54972416,"current-db-size":"55 MB","current-db-size-in-use-bytes":25690112,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T18:12:27.147226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3248937966,"revision":10386,"compact-revision":7972} {"level":"info","ts":"2026-10-14T18:17:27.065775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12540} {"level":"info","ts":"2026-10-14T18:17:27.149507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12540,"took":"81.148667ms","hash":167905189,"current-db-size-bytes":54972416,"current-db-size":"55 MB","current-db-size-in-use-bytes":27885568,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:17:27.149618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":167905189,"revision":12540,"compact-revision":10386} {"level":"info","ts":"2026-10-14T18:17:51.445886Z","caller":"traceutil/trace.go:171","msg":"trace[1176289837] linearizableReadLoop","detail":"{readStateIndex:15216; appliedIndex:15213; }","duration":"234.614399ms","start":"2026-10-14T18:17:51.211252Z","end":"2026-10-14T18:17:51.445867Z","steps":["trace[1176289837] 'read index received' (duration: 5.283536ms)","trace[1176289837] 'applied index is now lower than readState.Index' (duration: 229.330043ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:17:51.446022Z","caller":"traceutil/trace.go:171","msg":"trace[1452895757] transaction","detail":"{read_only:false; response_revision:14710; number_of_response:1; }","duration":"241.361063ms","start":"2026-10-14T18:17:51.204649Z","end":"2026-10-14T18:17:51.44601Z","steps":["trace[1452895757] 'process raft request' (duration: 190.616909ms)","trace[1452895757] 'compare' (duration: 50.405277ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:17:51.446228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.610144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j4m5g\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-14T18:17:51.446316Z","caller":"traceutil/trace.go:171","msg":"trace[1529946014] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j4m5g; range_end:; response_count:1; response_revision:14710; }","duration":"187.742817ms","start":"2026-10-14T18:17:51.258552Z","end":"2026-10-14T18:17:51.446295Z","steps":["trace[1529946014] 'agreement among raft nodes before linearized reading' (duration: 187.532703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:17:51.446585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.339693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T18:17:51.446614Z","caller":"traceutil/trace.go:171","msg":"trace[1286944334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14710; }","duration":"235.406974ms","start":"2026-10-14T18:17:51.211197Z","end":"2026-10-14T18:17:51.446604Z","steps":["trace[1286944334] 'agreement among raft nodes before linearized reading' (duration: 235.314852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:17:51.44699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.961605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:17:51.447029Z","caller":"traceutil/trace.go:171","msg":"trace[707661431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14710; }","duration":"152.020936ms","start":"2026-10-14T18:17:51.294996Z","end":"2026-10-14T18:17:51.447017Z","steps":["trace[707661431] 'agreement among raft nodes before linearized reading' (duration: 151.946755ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:11.243492Z","caller":"traceutil/trace.go:171","msg":"trace[844524826] transaction","detail":"{read_only:false; response_revision:14851; number_of_response:1; }","duration":"107.690649ms","start":"2026-10-14T18:18:11.135762Z","end":"2026-10-14T18:18:11.243453Z","steps":["trace[844524826] 'process raft request' (duration: 107.567037ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:31.842306Z","caller":"traceutil/trace.go:171","msg":"trace[389274141] linearizableReadLoop","detail":"{readStateIndex:15484; appliedIndex:15483; }","duration":"157.818692ms","start":"2026-10-14T18:18:31.684427Z","end":"2026-10-14T18:18:31.842245Z","steps":["trace[389274141] 'read index received' (duration: 157.65164ms)","trace[389274141] 'applied index is now lower than readState.Index' (duration: 165.522µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:18:31.842582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.141007ms","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-10-14T18:18:31.84265Z","caller":"traceutil/trace.go:171","msg":"trace[1633489874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14966; }","duration":"158.2535ms","start":"2026-10-14T18:18:31.684377Z","end":"2026-10-14T18:18:31.842631Z","steps":["trace[1633489874] 'agreement among raft nodes before linearized reading' (duration: 158.065876ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:31.842752Z","caller":"traceutil/trace.go:171","msg":"trace[1324292937] transaction","detail":"{read_only:false; response_revision:14966; number_of_response:1; }","duration":"190.433476ms","start":"2026-10-14T18:18:31.651866Z","end":"2026-10-14T18:18:31.8423Z","steps":["trace[1324292937] 'process raft request' (duration: 190.196192ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:31.991872Z","caller":"traceutil/trace.go:171","msg":"trace[1633270796] linearizableReadLoop","detail":"{readStateIndex:15485; appliedIndex:15484; }","duration":"143.455031ms","start":"2026-10-14T18:18:31.848396Z","end":"2026-10-14T18:18:31.991851Z","steps":["trace[1633270796] 'read index received' (duration: 141.564496ms)","trace[1633270796] 'applied index is now lower than readState.Index' (duration: 1.889385ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:18:31.991984Z","caller":"traceutil/trace.go:171","msg":"trace[505884533] transaction","detail":"{read_only:false; response_revision:14967; number_of_response:1; }","duration":"144.576651ms","start":"2026-10-14T18:18:31.847399Z","end":"2026-10-14T18:18:31.991975Z","steps":["trace[505884533] 'process raft request' (duration: 142.672176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:18:31.992683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.282076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:62 size:582665"} {"level":"info","ts":"2026-10-14T18:18:31.992761Z","caller":"traceutil/trace.go:171","msg":"trace[507178302] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:62; response_revision:14967; }","duration":"144.411408ms","start":"2026-10-14T18:18:31.84833Z","end":"2026-10-14T18:18:31.992741Z","steps":["trace[507178302] 'agreement among raft nodes before linearized reading' (duration: 143.731715ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:49.343247Z","caller":"traceutil/trace.go:171","msg":"trace[1586923001] transaction","detail":"{read_only:false; response_revision:15069; number_of_response:1; }","duration":"159.36418ms","start":"2026-10-14T18:18:49.18384Z","end":"2026-10-14T18:18:49.343204Z","steps":["trace[1586923001] 'process raft request' (duration: 159.066305ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:49.538193Z","caller":"traceutil/trace.go:171","msg":"trace[968794399] transaction","detail":"{read_only:false; response_revision:15070; number_of_response:1; }","duration":"158.724038ms","start":"2026-10-14T18:18:49.379432Z","end":"2026-10-14T18:18:49.538156Z","steps":["trace[968794399] 'process raft request' (duration: 154.245526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:19:00.80391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.971205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-14T18:19:00.803965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.8747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.166\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-10-14T18:19:00.803995Z","caller":"traceutil/trace.go:171","msg":"trace[1717665634] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:15248; }","duration":"191.090717ms","start":"2026-10-14T18:19:00.612882Z","end":"2026-10-14T18:19:00.803973Z","steps":["trace[1717665634] 'range keys from in-memory index tree' (duration: 190.809423ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:19:00.804357Z","caller":"traceutil/trace.go:171","msg":"trace[1437713148] range","detail":"{range_begin:/registry/masterleases/162.253.55.166; range_end:; response_count:1; response_revision:15248; }","duration":"193.322358ms","start":"2026-10-14T18:19:00.61101Z","end":"2026-10-14T18:19:00.804332Z","steps":["trace[1437713148] 'range keys from in-memory index tree' (duration: 192.644565ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:19:01.177111Z","caller":"traceutil/trace.go:171","msg":"trace[41709771] transaction","detail":"{read_only:false; response_revision:15249; number_of_response:1; }","duration":"368.369174ms","start":"2026-10-14T18:19:00.808702Z","end":"2026-10-14T18:19:01.177071Z","steps":["trace[41709771] 'process raft request' (duration: 299.139474ms)","trace[41709771] 'compare' (duration: 69.013186ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:19:01.177151Z","caller":"traceutil/trace.go:171","msg":"trace[1141662149] transaction","detail":"{read_only:false; response_revision:15250; number_of_response:1; }","duration":"191.683319ms","start":"2026-10-14T18:19:00.985442Z","end":"2026-10-14T18:19:01.177126Z","steps":["trace[1141662149] 'process raft request' (duration: 191.604427ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:19:01.17722Z","caller":"traceutil/trace.go:171","msg":"trace[1953274219] linearizableReadLoop","detail":"{readStateIndex:15776; appliedIndex:15775; }","duration":"302.417283ms","start":"2026-10-14T18:19:00.874751Z","end":"2026-10-14T18:19:01.177169Z","steps":["trace[1953274219] 'read index received' (duration: 109.917871ms)","trace[1953274219] 'applied index is now lower than readState.Index' (duration: 192.495942ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:19:01.177446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.684678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-8xgpl\" ","response":"range_response_count:1 size:7032"} {"level":"warn","ts":"2026-10-14T18:19:01.177467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.905249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-2m2bp.18de7844f8a525da\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-14T18:19:01.177509Z","caller":"traceutil/trace.go:171","msg":"trace[939325524] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-2m2bp.18de7844f8a525da; range_end:; response_count:1; response_revision:15250; }","duration":"282.974749ms","start":"2026-10-14T18:19:00.894521Z","end":"2026-10-14T18:19:01.177496Z","steps":["trace[939325524] 'agreement among raft nodes before linearized reading' (duration: 282.846467ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:19:01.177505Z","caller":"traceutil/trace.go:171","msg":"trace[91925557] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-8xgpl; range_end:; response_count:1; response_revision:15250; }","duration":"302.79854ms","start":"2026-10-14T18:19:00.874694Z","end":"2026-10-14T18:19:01.177492Z","steps":["trace[91925557] 'agreement among raft nodes before linearized reading' (duration: 302.553056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:19:01.177558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:19:00.874671Z","time spent":"302.878142ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7056,"request content":"key:\"/registry/pods/openstack/ovn-northd-fc978744-8xgpl\" "} {"level":"warn","ts":"2026-10-14T18:19:01.178299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:19:00.808678Z","time spent":"368.530166ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:20:42.436946Z","caller":"traceutil/trace.go:171","msg":"trace[1593305141] linearizableReadLoop","detail":"{readStateIndex:16902; appliedIndex:16901; }","duration":"319.984234ms","start":"2026-10-14T18:20:42.116934Z","end":"2026-10-14T18:20:42.436918Z","steps":["trace[1593305141] 'read index received' (duration: 319.77768ms)","trace[1593305141] 'applied index is now lower than readState.Index' (duration: 205.124µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:20:42.436986Z","caller":"traceutil/trace.go:171","msg":"trace[1373848546] transaction","detail":"{read_only:false; response_revision:16341; number_of_response:1; }","duration":"338.579276ms","start":"2026-10-14T18:20:42.098384Z","end":"2026-10-14T18:20:42.436964Z","steps":["trace[1373848546] 'process raft request' (duration: 338.333132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.437167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:20:42.098365Z","time spent":"338.719628ms","remote":"127.0.0.1:55226","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-10-14T18:20:42.437223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.884072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-14T18:20:42.437236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.077851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:20:42.437223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.27731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-sh7dn\" ","response":"range_response_count:1 size:20652"} {"level":"warn","ts":"2026-10-14T18:20:42.437301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.292154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:20:42.437297Z","caller":"traceutil/trace.go:171","msg":"trace[516961935] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16341; }","duration":"147.181583ms","start":"2026-10-14T18:20:42.290098Z","end":"2026-10-14T18:20:42.437279Z","steps":["trace[516961935] 'agreement among raft nodes before linearized reading' (duration: 147.068841ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:20:42.437305Z","caller":"traceutil/trace.go:171","msg":"trace[1623039770] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-sh7dn; range_end:; response_count:1; response_revision:16341; }","duration":"320.404232ms","start":"2026-10-14T18:20:42.116884Z","end":"2026-10-14T18:20:42.437288Z","steps":["trace[1623039770] 'agreement among raft nodes before linearized reading' (duration: 320.174558ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:20:42.437321Z","caller":"traceutil/trace.go:171","msg":"trace[1127232551] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16341; }","duration":"147.346956ms","start":"2026-10-14T18:20:42.289968Z","end":"2026-10-14T18:20:42.437315Z","steps":["trace[1127232551] 'agreement among raft nodes before linearized reading' (duration: 147.306075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.437312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.199883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:20:42.437393Z","caller":"traceutil/trace.go:171","msg":"trace[915433443] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16341; }","duration":"147.339446ms","start":"2026-10-14T18:20:42.290035Z","end":"2026-10-14T18:20:42.437375Z","steps":["trace[915433443] 'agreement among raft nodes before linearized reading' (duration: 147.187033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.437392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.654183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-14T18:20:42.437342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:20:42.116868Z","time spent":"320.465694ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20676,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-sh7dn\" "} {"level":"info","ts":"2026-10-14T18:20:42.437448Z","caller":"traceutil/trace.go:171","msg":"trace[898751180] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16341; }","duration":"146.732304ms","start":"2026-10-14T18:20:42.290702Z","end":"2026-10-14T18:20:42.437434Z","steps":["trace[898751180] 'agreement among raft nodes before linearized reading' (duration: 146.592811ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:20:42.437255Z","caller":"traceutil/trace.go:171","msg":"trace[732244772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16341; }","duration":"307.939013ms","start":"2026-10-14T18:20:42.129305Z","end":"2026-10-14T18:20:42.437244Z","steps":["trace[732244772] 'agreement among raft nodes before linearized reading' (duration: 307.812611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.437535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:20:42.129292Z","time spent":"308.236219ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T18:20:42.729081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.218912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:20:42.729186Z","caller":"traceutil/trace.go:171","msg":"trace[84171821] linearizableReadLoop","detail":"{readStateIndex:16903; appliedIndex:16902; }","duration":"213.447087ms","start":"2026-10-14T18:20:42.515723Z","end":"2026-10-14T18:20:42.72917Z","steps":["trace[84171821] 'read index received' (duration: 36.109663ms)","trace[84171821] 'applied index is now lower than readState.Index' (duration: 177.336054ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:20:42.729292Z","caller":"traceutil/trace.go:171","msg":"trace[1043782793] transaction","detail":"{read_only:false; response_revision:16342; number_of_response:1; }","duration":"285.352289ms","start":"2026-10-14T18:20:42.443928Z","end":"2026-10-14T18:20:42.72928Z","steps":["trace[1043782793] 'process raft request' (duration: 107.867681ms)","trace[1043782793] 'compare' (duration: 177.09095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:20:42.729489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.890607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ttnj2\" ","response":"range_response_count:1 size:22125"} {"level":"warn","ts":"2026-10-14T18:20:42.729522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.789194ms","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-10-14T18:20:42.729572Z","caller":"traceutil/trace.go:171","msg":"trace[2056949279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16342; }","duration":"213.878816ms","start":"2026-10-14T18:20:42.515679Z","end":"2026-10-14T18:20:42.729558Z","steps":["trace[2056949279] 'agreement among raft nodes before linearized reading' (duration: 213.753463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.729571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.258779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-14T18:20:42.729571Z","caller":"traceutil/trace.go:171","msg":"trace[1082801583] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ttnj2; range_end:; response_count:1; response_revision:16342; }","duration":"171.005049ms","start":"2026-10-14T18:20:42.558548Z","end":"2026-10-14T18:20:42.729553Z","steps":["trace[1082801583] 'agreement among raft nodes before linearized reading' (duration: 170.810995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.729665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.59226ms","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-10-14T18:20:42.729666Z","caller":"traceutil/trace.go:171","msg":"trace[616856286] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16342; }","duration":"101.378211ms","start":"2026-10-14T18:20:42.628266Z","end":"2026-10-14T18:20:42.729645Z","steps":["trace[616856286] 'agreement among raft nodes before linearized reading' (duration: 101.130847ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:20:42.729693Z","caller":"traceutil/trace.go:171","msg":"trace[130991099] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16342; }","duration":"104.64048ms","start":"2026-10-14T18:20:42.625043Z","end":"2026-10-14T18:20:42.729684Z","steps":["trace[130991099] 'agreement among raft nodes before linearized reading' (duration: 104.550038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.729874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.412933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:20:42.729921Z","caller":"traceutil/trace.go:171","msg":"trace[401744888] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16342; }","duration":"106.488555ms","start":"2026-10-14T18:20:42.62342Z","end":"2026-10-14T18:20:42.729908Z","steps":["trace[401744888] 'agreement among raft nodes before linearized reading' (duration: 106.414144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.729957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.700507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ttnj2\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-10-14T18:20:42.729992Z","caller":"traceutil/trace.go:171","msg":"trace[40453658] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ttnj2; range_end:; response_count:1; response_revision:16342; }","duration":"149.735378ms","start":"2026-10-14T18:20:42.580248Z","end":"2026-10-14T18:20:42.729983Z","steps":["trace[40453658] 'agreement among raft nodes before linearized reading' (duration: 149.626456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.729988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.053681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:20:42.730042Z","caller":"traceutil/trace.go:171","msg":"trace[731396007] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16342; }","duration":"103.124563ms","start":"2026-10-14T18:20:42.626901Z","end":"2026-10-14T18:20:42.730026Z","steps":["trace[731396007] 'agreement among raft nodes before linearized reading' (duration: 103.040852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.730056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.862066ms","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-10-14T18:20:42.730088Z","caller":"traceutil/trace.go:171","msg":"trace[1471014310] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16342; }","duration":"104.932935ms","start":"2026-10-14T18:20:42.625145Z","end":"2026-10-14T18:20:42.730078Z","steps":["trace[1471014310] 'agreement among raft nodes before linearized reading' (duration: 104.228722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.730147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.660039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-14T18:20:42.730181Z","caller":"traceutil/trace.go:171","msg":"trace[298993305] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16342; }","duration":"106.715789ms","start":"2026-10-14T18:20:42.623457Z","end":"2026-10-14T18:20:42.730173Z","steps":["trace[298993305] 'agreement among raft nodes before linearized reading' (duration: 106.596177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.730212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.074051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-14T18:20:42.730245Z","caller":"traceutil/trace.go:171","msg":"trace[765925624] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16342; }","duration":"104.126161ms","start":"2026-10-14T18:20:42.62611Z","end":"2026-10-14T18:20:42.730237Z","steps":["trace[765925624] 'agreement among raft nodes before linearized reading' (duration: 104.04304ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:20:42.899036Z","caller":"traceutil/trace.go:171","msg":"trace[1951180240] linearizableReadLoop","detail":"{readStateIndex:16904; appliedIndex:16903; }","duration":"164.151024ms","start":"2026-10-14T18:20:42.734862Z","end":"2026-10-14T18:20:42.899013Z","steps":["trace[1951180240] 'read index received' (duration: 153.266324ms)","trace[1951180240] 'applied index is now lower than readState.Index' (duration: 10.88385ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:20:42.899112Z","caller":"traceutil/trace.go:171","msg":"trace[319850851] transaction","detail":"{read_only:false; response_revision:16343; number_of_response:1; }","duration":"164.693534ms","start":"2026-10-14T18:20:42.734389Z","end":"2026-10-14T18:20:42.899082Z","steps":["trace[319850851] 'process raft request' (duration: 153.806245ms)","trace[319850851] 'compare' (duration: 10.459441ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:20:42.899273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.391539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T18:20:42.89931Z","caller":"traceutil/trace.go:171","msg":"trace[930177378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16343; }","duration":"164.466221ms","start":"2026-10-14T18:20:42.734835Z","end":"2026-10-14T18:20:42.899301Z","steps":["trace[930177378] 'agreement among raft nodes before linearized reading' (duration: 164.298057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.899355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.048152ms","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-10-14T18:20:42.899406Z","caller":"traceutil/trace.go:171","msg":"trace[387749809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16343; }","duration":"158.116483ms","start":"2026-10-14T18:20:42.741276Z","end":"2026-10-14T18:20:42.899392Z","steps":["trace[387749809] 'agreement among raft nodes before linearized reading' (duration: 157.986291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.899393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.50223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T18:20:42.89947Z","caller":"traceutil/trace.go:171","msg":"trace[1125309032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16343; }","duration":"164.624433ms","start":"2026-10-14T18:20:42.734827Z","end":"2026-10-14T18:20:42.899452Z","steps":["trace[1125309032] 'agreement among raft nodes before linearized reading' (duration: 164.288857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.90011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.999022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:770003"} {"level":"info","ts":"2026-10-14T18:20:42.900149Z","caller":"traceutil/trace.go:171","msg":"trace[1782508704] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16343; }","duration":"157.069353ms","start":"2026-10-14T18:20:42.743072Z","end":"2026-10-14T18:20:42.900141Z","steps":["trace[1782508704] 'agreement among raft nodes before linearized reading' (duration: 156.225088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:42.900485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.965189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:770003"} {"level":"info","ts":"2026-10-14T18:20:42.90057Z","caller":"traceutil/trace.go:171","msg":"trace[276083699] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16343; }","duration":"158.094582ms","start":"2026-10-14T18:20:42.742454Z","end":"2026-10-14T18:20:42.900549Z","steps":["trace[276083699] 'agreement among raft nodes before linearized reading' (duration: 156.988132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:43.16955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.908328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:20:43.169769Z","caller":"traceutil/trace.go:171","msg":"trace[1380360292] linearizableReadLoop","detail":"{readStateIndex:16907; appliedIndex:16905; }","duration":"211.176787ms","start":"2026-10-14T18:20:42.958576Z","end":"2026-10-14T18:20:43.169753Z","steps":["trace[1380360292] 'read index received' (duration: 46.892092ms)","trace[1380360292] 'applied index is now lower than readState.Index' (duration: 164.283675ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:20:43.169913Z","caller":"traceutil/trace.go:171","msg":"trace[1382273476] transaction","detail":"{read_only:false; response_revision:16346; number_of_response:1; }","duration":"262.600451ms","start":"2026-10-14T18:20:42.907298Z","end":"2026-10-14T18:20:43.169899Z","steps":["trace[1382273476] 'process raft request' (duration: 262.353486ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:20:43.169998Z","caller":"traceutil/trace.go:171","msg":"trace[1573056995] transaction","detail":"{read_only:false; response_revision:16345; number_of_response:1; }","duration":"263.019918ms","start":"2026-10-14T18:20:42.906951Z","end":"2026-10-14T18:20:43.169971Z","steps":["trace[1573056995] 'process raft request' (duration: 98.58135ms)","trace[1573056995] 'compare' (duration: 163.689974ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:20:43.170286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.900829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-sxxc6\" ","response":"range_response_count:1 size:9799"} {"level":"warn","ts":"2026-10-14T18:20:43.170299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.680537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-14T18:20:43.170333Z","caller":"traceutil/trace.go:171","msg":"trace[663024523] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-sxxc6; range_end:; response_count:1; response_revision:16346; }","duration":"175.974761ms","start":"2026-10-14T18:20:42.994346Z","end":"2026-10-14T18:20:43.170321Z","steps":["trace[663024523] 'agreement among raft nodes before linearized reading' (duration: 175.815048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:43.170304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.697337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:20:43.170367Z","caller":"traceutil/trace.go:171","msg":"trace[1068103580] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16346; }","duration":"211.791028ms","start":"2026-10-14T18:20:42.958556Z","end":"2026-10-14T18:20:43.170347Z","steps":["trace[1068103580] 'agreement among raft nodes before linearized reading' (duration: 211.582884ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:20:43.170402Z","caller":"traceutil/trace.go:171","msg":"trace[840855862] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16346; }","duration":"211.84275ms","start":"2026-10-14T18:20:42.95854Z","end":"2026-10-14T18:20:43.170383Z","steps":["trace[840855862] 'agreement among raft nodes before linearized reading' (duration: 211.667147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:43.170818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.177916ms","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-10-14T18:20:43.171067Z","caller":"traceutil/trace.go:171","msg":"trace[1274075811] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16346; }","duration":"212.43751ms","start":"2026-10-14T18:20:42.958614Z","end":"2026-10-14T18:20:43.171052Z","steps":["trace[1274075811] 'agreement among raft nodes before linearized reading' (duration: 211.578824ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:22:27.072239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14551} {"level":"info","ts":"2026-10-14T18:22:27.154488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14551,"took":"79.186893ms","hash":2624290219,"current-db-size-bytes":54972416,"current-db-size":"55 MB","current-db-size-in-use-bytes":31698944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:22:27.154564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2624290219,"revision":14551,"compact-revision":12540} {"level":"info","ts":"2026-10-14T18:26:17.531892Z","caller":"traceutil/trace.go:171","msg":"trace[1201738367] linearizableReadLoop","detail":"{readStateIndex:19475; appliedIndex:19474; }","duration":"167.520365ms","start":"2026-10-14T18:26:17.364354Z","end":"2026-10-14T18:26:17.531874Z","steps":["trace[1201738367] 'read index received' (duration: 144.068845ms)","trace[1201738367] 'applied index is now lower than readState.Index' (duration: 23.45099ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:17.531973Z","caller":"traceutil/trace.go:171","msg":"trace[790699983] transaction","detail":"{read_only:false; response_revision:18831; number_of_response:1; }","duration":"168.172227ms","start":"2026-10-14T18:26:17.363792Z","end":"2026-10-14T18:26:17.531964Z","steps":["trace[790699983] 'process raft request' (duration: 144.590174ms)","trace[790699983] 'compare' (duration: 23.42312ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:26:17.532165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.797971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-14T18:26:17.532188Z","caller":"traceutil/trace.go:171","msg":"trace[777507924] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:18831; }","duration":"167.833411ms","start":"2026-10-14T18:26:17.364347Z","end":"2026-10-14T18:26:17.532181Z","steps":["trace[777507924] 'agreement among raft nodes before linearized reading' (duration: 167.736939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:17.532304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.278178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ttnj2\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-14T18:26:17.532319Z","caller":"traceutil/trace.go:171","msg":"trace[1244337270] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ttnj2; range_end:; response_count:1; response_revision:18831; }","duration":"120.321529ms","start":"2026-10-14T18:26:17.411994Z","end":"2026-10-14T18:26:17.532316Z","steps":["trace[1244337270] 'agreement among raft nodes before linearized reading' (duration: 120.271948ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:19.296888Z","caller":"traceutil/trace.go:171","msg":"trace[1961637941] linearizableReadLoop","detail":"{readStateIndex:19492; appliedIndex:19491; }","duration":"216.803031ms","start":"2026-10-14T18:26:19.080061Z","end":"2026-10-14T18:26:19.296864Z","steps":["trace[1961637941] 'read index received' (duration: 216.619197ms)","trace[1961637941] 'applied index is now lower than readState.Index' (duration: 182.714µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:19.296962Z","caller":"traceutil/trace.go:171","msg":"trace[708865321] transaction","detail":"{read_only:false; response_revision:18848; number_of_response:1; }","duration":"273.914049ms","start":"2026-10-14T18:26:19.023013Z","end":"2026-10-14T18:26:19.296927Z","steps":["trace[708865321] 'process raft request' (duration: 273.676104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:19.297087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.004104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T18:26:19.297122Z","caller":"traceutil/trace.go:171","msg":"trace[1252716234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18848; }","duration":"217.095896ms","start":"2026-10-14T18:26:19.080017Z","end":"2026-10-14T18:26:19.297113Z","steps":["trace[1252716234] 'agreement among raft nodes before linearized reading' (duration: 216.955094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:19.297823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.591174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-14T18:26:19.2979Z","caller":"traceutil/trace.go:171","msg":"trace[1066732863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18849; }","duration":"217.698007ms","start":"2026-10-14T18:26:19.080186Z","end":"2026-10-14T18:26:19.297884Z","steps":["trace[1066732863] 'agreement among raft nodes before linearized reading' (duration: 217.483453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:19.298008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.728433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-14T18:26:19.298088Z","caller":"traceutil/trace.go:171","msg":"trace[1416365347] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:18849; }","duration":"146.880736ms","start":"2026-10-14T18:26:19.151191Z","end":"2026-10-14T18:26:19.298072Z","steps":["trace[1416365347] 'agreement among raft nodes before linearized reading' (duration: 146.653392ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:21.436168Z","caller":"traceutil/trace.go:171","msg":"trace[1133034659] transaction","detail":"{read_only:false; response_revision:18918; number_of_response:1; }","duration":"242.564012ms","start":"2026-10-14T18:26:21.19358Z","end":"2026-10-14T18:26:21.436144Z","steps":["trace[1133034659] 'process raft request' (duration: 159.651411ms)","trace[1133034659] 'compare' (duration: 82.563246ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:21.436345Z","caller":"traceutil/trace.go:171","msg":"trace[447963261] linearizableReadLoop","detail":"{readStateIndex:19563; appliedIndex:19562; }","duration":"141.545878ms","start":"2026-10-14T18:26:21.294772Z","end":"2026-10-14T18:26:21.436318Z","steps":["trace[447963261] 'read index received' (duration: 58.472743ms)","trace[447963261] 'applied index is now lower than readState.Index' (duration: 83.070725ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:26:21.436509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.767792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:26:21.436573Z","caller":"traceutil/trace.go:171","msg":"trace[339192001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18918; }","duration":"141.921374ms","start":"2026-10-14T18:26:21.294637Z","end":"2026-10-14T18:26:21.436558Z","steps":["trace[339192001] 'agreement among raft nodes before linearized reading' (duration: 141.794862ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:21.436601Z","caller":"traceutil/trace.go:171","msg":"trace[1340343789] transaction","detail":"{read_only:false; response_revision:18919; number_of_response:1; }","duration":"131.951183ms","start":"2026-10-14T18:26:21.304621Z","end":"2026-10-14T18:26:21.436572Z","steps":["trace[1340343789] 'process raft request' (duration: 131.857421ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:21.436765Z","caller":"traceutil/trace.go:171","msg":"trace[230627626] transaction","detail":"{read_only:false; response_revision:18920; number_of_response:1; }","duration":"110.345695ms","start":"2026-10-14T18:26:21.326409Z","end":"2026-10-14T18:26:21.436755Z","steps":["trace[230627626] 'process raft request' (duration: 110.132621ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:21.436853Z","caller":"traceutil/trace.go:171","msg":"trace[457503816] transaction","detail":"{read_only:false; response_revision:18921; number_of_response:1; }","duration":"108.40751ms","start":"2026-10-14T18:26:21.328438Z","end":"2026-10-14T18:26:21.436846Z","steps":["trace[457503816] 'process raft request' (duration: 108.255567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:21.436853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.053026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T18:26:21.436882Z","caller":"traceutil/trace.go:171","msg":"trace[544773191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18921; }","duration":"130.129718ms","start":"2026-10-14T18:26:21.306744Z","end":"2026-10-14T18:26:21.436874Z","steps":["trace[544773191] 'agreement among raft nodes before linearized reading' (duration: 130.016976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:21.436931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.260088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-10-14T18:26:21.436956Z","caller":"traceutil/trace.go:171","msg":"trace[1412525327] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18921; }","duration":"113.30391ms","start":"2026-10-14T18:26:21.323642Z","end":"2026-10-14T18:26:21.436946Z","steps":["trace[1412525327] 'agreement among raft nodes before linearized reading' (duration: 113.208658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:21.43698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.294348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-14T18:26:21.437008Z","caller":"traceutil/trace.go:171","msg":"trace[1565777313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18921; }","duration":"120.36033ms","start":"2026-10-14T18:26:21.31664Z","end":"2026-10-14T18:26:21.437001Z","steps":["trace[1565777313] 'agreement among raft nodes before linearized reading' (duration: 120.279528ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:23.663972Z","caller":"traceutil/trace.go:171","msg":"trace[1707101993] linearizableReadLoop","detail":"{readStateIndex:19579; appliedIndex:19576; }","duration":"202.732243ms","start":"2026-10-14T18:26:23.46122Z","end":"2026-10-14T18:26:23.663952Z","steps":["trace[1707101993] 'read index received' (duration: 7.681761ms)","trace[1707101993] 'applied index is now lower than readState.Index' (duration: 195.049462ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:23.663991Z","caller":"traceutil/trace.go:171","msg":"trace[106694068] transaction","detail":"{read_only:false; response_revision:18933; number_of_response:1; }","duration":"220.52928ms","start":"2026-10-14T18:26:23.443433Z","end":"2026-10-14T18:26:23.663962Z","steps":["trace[106694068] 'process raft request' (duration: 169.383761ms)","trace[106694068] 'compare' (duration: 50.627559ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:23.664087Z","caller":"traceutil/trace.go:171","msg":"trace[1689133054] transaction","detail":"{read_only:false; response_revision:18934; number_of_response:1; }","duration":"218.409069ms","start":"2026-10-14T18:26:23.44564Z","end":"2026-10-14T18:26:23.664049Z","steps":["trace[1689133054] 'process raft request' (duration: 218.227006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:23.664133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.897106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T18:26:23.664192Z","caller":"traceutil/trace.go:171","msg":"trace[647429065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18934; }","duration":"202.965227ms","start":"2026-10-14T18:26:23.461215Z","end":"2026-10-14T18:26:23.66418Z","steps":["trace[647429065] 'agreement among raft nodes before linearized reading' (duration: 202.822924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:23.664198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.840704ms","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-10-14T18:26:23.664271Z","caller":"traceutil/trace.go:171","msg":"trace[184227601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18934; }","duration":"202.961716ms","start":"2026-10-14T18:26:23.461291Z","end":"2026-10-14T18:26:23.664253Z","steps":["trace[184227601] 'agreement among raft nodes before linearized reading' (duration: 202.793943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:26.576311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16085909246095791969,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-14T18:26:26.621784Z","caller":"traceutil/trace.go:171","msg":"trace[509869131] linearizableReadLoop","detail":"{readStateIndex:19606; appliedIndex:19605; }","duration":"546.012574ms","start":"2026-10-14T18:26:26.075744Z","end":"2026-10-14T18:26:26.621756Z","steps":["trace[509869131] 'read index received' (duration: 545.835611ms)","trace[509869131] 'applied index is now lower than readState.Index' (duration: 175.703µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:26.621869Z","caller":"traceutil/trace.go:171","msg":"trace[785644026] transaction","detail":"{read_only:false; response_revision:18960; number_of_response:1; }","duration":"655.703128ms","start":"2026-10-14T18:26:25.966134Z","end":"2026-10-14T18:26:26.621838Z","steps":["trace[785644026] 'process raft request' (duration: 655.464033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:26.62194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.169677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:26:26.621975Z","caller":"traceutil/trace.go:171","msg":"trace[1763129609] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:18960; }","duration":"546.276999ms","start":"2026-10-14T18:26:26.075686Z","end":"2026-10-14T18:26:26.621963Z","steps":["trace[1763129609] 'agreement among raft nodes before linearized reading' (duration: 546.196817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:26.622004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:26.075667Z","time spent":"546.32762ms","remote":"127.0.0.1:51484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:26:26.622031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:25.966112Z","time spent":"655.819849ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9138,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:26:26.622103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.920214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:26:26.622183Z","caller":"traceutil/trace.go:171","msg":"trace[1136244314] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:18960; }","duration":"490.050907ms","start":"2026-10-14T18:26:26.132112Z","end":"2026-10-14T18:26:26.622162Z","steps":["trace[1136244314] 'agreement among raft nodes before linearized reading' (duration: 489.864883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:26.622181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.541745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:26:26.622227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:26.132099Z","time spent":"490.117788ms","remote":"127.0.0.1:52902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:26:26.622241Z","caller":"traceutil/trace.go:171","msg":"trace[1826855736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18960; }","duration":"326.638926ms","start":"2026-10-14T18:26:26.295584Z","end":"2026-10-14T18:26:26.622223Z","steps":["trace[1826855736] 'agreement among raft nodes before linearized reading' (duration: 326.550154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:26.622282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:26.295564Z","time spent":"326.707978ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:26:26.622447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.110549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-sh7dn\" ","response":"range_response_count:1 size:20755"} {"level":"warn","ts":"2026-10-14T18:26:26.622465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.533481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T18:26:26.622499Z","caller":"traceutil/trace.go:171","msg":"trace[1172839930] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-sh7dn; range_end:; response_count:1; response_revision:18960; }","duration":"465.19376ms","start":"2026-10-14T18:26:26.157277Z","end":"2026-10-14T18:26:26.622471Z","steps":["trace[1172839930] 'agreement among raft nodes before linearized reading' (duration: 465.064698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:26.622536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:26.15725Z","time spent":"465.279322ms","remote":"127.0.0.1:55148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20779,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-sh7dn\" "} {"level":"info","ts":"2026-10-14T18:26:26.622543Z","caller":"traceutil/trace.go:171","msg":"trace[1286535483] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18960; }","duration":"182.650433ms","start":"2026-10-14T18:26:26.439874Z","end":"2026-10-14T18:26:26.622525Z","steps":["trace[1286535483] 'agreement among raft nodes before linearized reading' (duration: 182.535111ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:26.750564Z","caller":"traceutil/trace.go:171","msg":"trace[157997752] transaction","detail":"{read_only:false; response_revision:18962; number_of_response:1; }","duration":"105.953825ms","start":"2026-10-14T18:26:26.644592Z","end":"2026-10-14T18:26:26.750546Z","steps":["trace[157997752] 'process raft request' (duration: 105.652659ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:55.515285Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:26:55.521781Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T18:26:55.521867Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T18:27:27.082618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17009} {"level":"info","ts":"2026-10-14T18:27:27.187411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17009,"took":"101.136477ms","hash":1192459221,"current-db-size-bytes":54972416,"current-db-size":"55 MB","current-db-size-in-use-bytes":34803712,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T18:27:27.187478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1192459221,"revision":17009,"compact-revision":14551} {"level":"info","ts":"2026-10-14T18:32:15.152696Z","caller":"traceutil/trace.go:171","msg":"trace[2140628228] transaction","detail":"{read_only:false; response_revision:21583; number_of_response:1; }","duration":"253.921538ms","start":"2026-10-14T18:32:14.89875Z","end":"2026-10-14T18:32:15.152671Z","steps":["trace[2140628228] 'process raft request' (duration: 253.740604ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:15.174691Z","caller":"traceutil/trace.go:171","msg":"trace[555224537] transaction","detail":"{read_only:false; response_revision:21584; number_of_response:1; }","duration":"165.332829ms","start":"2026-10-14T18:32:15.009307Z","end":"2026-10-14T18:32:15.17464Z","steps":["trace[555224537] 'process raft request' (duration: 165.067234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:21.127869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.214416ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"warn","ts":"2026-10-14T18:32:21.128008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:20.730289Z","time spent":"397.713641ms","remote":"127.0.0.1:55004","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-14T18:32:21.129635Z","caller":"traceutil/trace.go:171","msg":"trace[427794321] linearizableReadLoop","detail":"{readStateIndex:22347; appliedIndex:22344; }","duration":"366.596218ms","start":"2026-10-14T18:32:20.763009Z","end":"2026-10-14T18:32:21.129605Z","steps":["trace[427794321] 'read index received' (duration: 236.675511ms)","trace[427794321] 'applied index is now lower than readState.Index' (duration: 129.919647ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:21.129779Z","caller":"traceutil/trace.go:171","msg":"trace[306465279] transaction","detail":"{read_only:false; response_revision:21611; number_of_response:1; }","duration":"395.161844ms","start":"2026-10-14T18:32:20.734603Z","end":"2026-10-14T18:32:21.129765Z","steps":["trace[306465279] 'process raft request' (duration: 394.957381ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:21.129726Z","caller":"traceutil/trace.go:171","msg":"trace[616202624] transaction","detail":"{read_only:false; response_revision:21610; number_of_response:1; }","duration":"395.152484ms","start":"2026-10-14T18:32:20.734493Z","end":"2026-10-14T18:32:21.129646Z","steps":["trace[616202624] 'process raft request' (duration: 394.895779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:21.129886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:20.73457Z","time spent":"395.238346ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:32:21.129972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:20.734475Z","time spent":"395.361018ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:32:21.130116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.099459ms","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-10-14T18:32:21.130185Z","caller":"traceutil/trace.go:171","msg":"trace[1376604566] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:21611; }","duration":"367.223631ms","start":"2026-10-14T18:32:20.76294Z","end":"2026-10-14T18:32:21.130163Z","steps":["trace[1376604566] 'agreement among raft nodes before linearized reading' (duration: 367.065298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:21.130231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:32:20.762902Z","time spent":"367.319612ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":32,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:32:21.148935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.470779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:32:21.149003Z","caller":"traceutil/trace.go:171","msg":"trace[89147779] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:21612; }","duration":"228.549561ms","start":"2026-10-14T18:32:20.920439Z","end":"2026-10-14T18:32:21.148988Z","steps":["trace[89147779] 'agreement among raft nodes before linearized reading' (duration: 228.395978ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:21.418971Z","caller":"traceutil/trace.go:171","msg":"trace[1702889808] transaction","detail":"{read_only:false; response_revision:21613; number_of_response:1; }","duration":"248.61107ms","start":"2026-10-14T18:32:21.17032Z","end":"2026-10-14T18:32:21.418931Z","steps":["trace[1702889808] 'process raft request' (duration: 171.751487ms)","trace[1702889808] 'compare' (duration: 76.647069ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:21.433137Z","caller":"traceutil/trace.go:171","msg":"trace[899529592] transaction","detail":"{read_only:false; response_revision:21615; number_of_response:1; }","duration":"198.530469ms","start":"2026-10-14T18:32:21.23458Z","end":"2026-10-14T18:32:21.43311Z","steps":["trace[899529592] 'process raft request' (duration: 198.437857ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:21.433163Z","caller":"traceutil/trace.go:171","msg":"trace[1784340809] transaction","detail":"{read_only:false; response_revision:21614; number_of_response:1; }","duration":"238.958523ms","start":"2026-10-14T18:32:21.194179Z","end":"2026-10-14T18:32:21.433138Z","steps":["trace[1784340809] 'process raft request' (duration: 238.715888ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:21.433298Z","caller":"traceutil/trace.go:171","msg":"trace[1683808979] linearizableReadLoop","detail":"{readStateIndex:22351; appliedIndex:22348; }","duration":"138.222711ms","start":"2026-10-14T18:32:21.295054Z","end":"2026-10-14T18:32:21.433276Z","steps":["trace[1683808979] 'read index received' (duration: 46.993083ms)","trace[1683808979] 'applied index is now lower than readState.Index' (duration: 91.227558ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:32:21.43342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.368924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:32:21.433379Z","caller":"traceutil/trace.go:171","msg":"trace[939068886] transaction","detail":"{read_only:false; response_revision:21616; number_of_response:1; }","duration":"129.076963ms","start":"2026-10-14T18:32:21.30425Z","end":"2026-10-14T18:32:21.433327Z","steps":["trace[939068886] 'process raft request' (duration: 128.806848ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:21.433459Z","caller":"traceutil/trace.go:171","msg":"trace[1568119788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21616; }","duration":"138.439335ms","start":"2026-10-14T18:32:21.295007Z","end":"2026-10-14T18:32:21.433446Z","steps":["trace[1568119788] 'agreement among raft nodes before linearized reading' (duration: 138.376564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:21.433558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.490301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-10-14T18:32:21.433605Z","caller":"traceutil/trace.go:171","msg":"trace[221219386] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:21616; }","duration":"129.581882ms","start":"2026-10-14T18:32:21.304008Z","end":"2026-10-14T18:32:21.43359Z","steps":["trace[221219386] 'agreement among raft nodes before linearized reading' (duration: 129.390588ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:22.082473Z","caller":"traceutil/trace.go:171","msg":"trace[46668736] linearizableReadLoop","detail":"{readStateIndex:22354; appliedIndex:22353; }","duration":"187.118839ms","start":"2026-10-14T18:32:21.895325Z","end":"2026-10-14T18:32:22.082443Z","steps":["trace[46668736] 'read index received' (duration: 160.265365ms)","trace[46668736] 'applied index is now lower than readState.Index' (duration: 26.851724ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:22.082627Z","caller":"traceutil/trace.go:171","msg":"trace[492483709] transaction","detail":"{read_only:false; response_revision:21618; number_of_response:1; }","duration":"207.874192ms","start":"2026-10-14T18:32:21.874694Z","end":"2026-10-14T18:32:22.082568Z","steps":["trace[492483709] 'process raft request' (duration: 181.042068ms)","trace[492483709] 'compare' (duration: 26.267223ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:32:22.082708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.363504ms","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-10-14T18:32:22.082997Z","caller":"traceutil/trace.go:171","msg":"trace[1207448609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21618; }","duration":"187.66955ms","start":"2026-10-14T18:32:21.895279Z","end":"2026-10-14T18:32:22.082948Z","steps":["trace[1207448609] 'agreement among raft nodes before linearized reading' (duration: 187.258592ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:22.312131Z","caller":"traceutil/trace.go:171","msg":"trace[269240568] linearizableReadLoop","detail":"{readStateIndex:22355; appliedIndex:22354; }","duration":"115.29971ms","start":"2026-10-14T18:32:22.196797Z","end":"2026-10-14T18:32:22.312097Z","steps":["trace[269240568] 'read index received' (duration: 75.655461ms)","trace[269240568] 'applied index is now lower than readState.Index' (duration: 39.642819ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:22.312306Z","caller":"traceutil/trace.go:171","msg":"trace[1363828356] transaction","detail":"{read_only:false; response_revision:21619; number_of_response:1; }","duration":"222.99867ms","start":"2026-10-14T18:32:22.089292Z","end":"2026-10-14T18:32:22.312291Z","steps":["trace[1363828356] 'process raft request' (duration: 183.174628ms)","trace[1363828356] 'compare' (duration: 39.476385ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:32:22.312461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.634706ms","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-10-14T18:32:22.312541Z","caller":"traceutil/trace.go:171","msg":"trace[622230153] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:21619; }","duration":"115.776709ms","start":"2026-10-14T18:32:22.196744Z","end":"2026-10-14T18:32:22.312521Z","steps":["trace[622230153] 'agreement among raft nodes before linearized reading' (duration: 115.622936ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:27.091071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19645} {"level":"info","ts":"2026-10-14T18:32:27.22556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19645,"took":"131.10467ms","hash":3597882378,"current-db-size-bytes":54972416,"current-db-size":"55 MB","current-db-size-in-use-bytes":29908992,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:32:27.225632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3597882378,"revision":19645,"compact-revision":17009} {"level":"info","ts":"2026-10-14T18:33:14.141977Z","caller":"traceutil/trace.go:171","msg":"trace[1602969341] transaction","detail":"{read_only:false; response_revision:22151; number_of_response:1; }","duration":"100.040909ms","start":"2026-10-14T18:33:14.041912Z","end":"2026-10-14T18:33:14.141953Z","steps":["trace[1602969341] 'process raft request' (duration: 99.684612ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:24.846512Z","caller":"traceutil/trace.go:171","msg":"trace[1441041970] transaction","detail":"{read_only:false; response_revision:24120; number_of_response:1; }","duration":"326.482701ms","start":"2026-10-14T18:36:24.519997Z","end":"2026-10-14T18:36:24.84648Z","steps":["trace[1441041970] 'process raft request' (duration: 326.392979ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:24.846616Z","caller":"traceutil/trace.go:171","msg":"trace[276452378] transaction","detail":"{read_only:false; response_revision:24119; number_of_response:1; }","duration":"334.924806ms","start":"2026-10-14T18:36:24.511636Z","end":"2026-10-14T18:36:24.846561Z","steps":["trace[276452378] 'process raft request' (duration: 334.60621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:24.846705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:24.519953Z","time spent":"326.666674ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:36:24.846779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:24.511586Z","time spent":"335.10404ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:36:24.846859Z","caller":"traceutil/trace.go:171","msg":"trace[663389177] linearizableReadLoop","detail":"{readStateIndex:24935; appliedIndex:24932; }","duration":"289.415571ms","start":"2026-10-14T18:36:24.557433Z","end":"2026-10-14T18:36:24.846848Z","steps":["trace[663389177] 'read index received' (duration: 288.881131ms)","trace[663389177] 'applied index is now lower than readState.Index' (duration: 533.329µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:36:24.846946Z","caller":"traceutil/trace.go:171","msg":"trace[771311715] transaction","detail":"{read_only:false; response_revision:24121; number_of_response:1; }","duration":"321.556841ms","start":"2026-10-14T18:36:24.52536Z","end":"2026-10-14T18:36:24.846917Z","steps":["trace[771311715] 'process raft request' (duration: 321.081892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:24.847001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.533314ms","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-10-14T18:36:24.84703Z","caller":"traceutil/trace.go:171","msg":"trace[398045408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24121; }","duration":"362.583286ms","start":"2026-10-14T18:36:24.484438Z","end":"2026-10-14T18:36:24.847021Z","steps":["trace[398045408] 'agreement among raft nodes before linearized reading' (duration: 362.470944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:24.847061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:24.484427Z","time spent":"362.627666ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T18:36:24.847095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:24.525327Z","time spent":"321.692834ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:36:24.84904Z","caller":"traceutil/trace.go:171","msg":"trace[902805764] transaction","detail":"{read_only:false; response_revision:24122; number_of_response:1; }","duration":"285.296974ms","start":"2026-10-14T18:36:24.563716Z","end":"2026-10-14T18:36:24.849013Z","steps":["trace[902805764] 'process raft request' (duration: 285.136591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:24.849346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.494724ms","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:6"} {"level":"info","ts":"2026-10-14T18:36:24.849428Z","caller":"traceutil/trace.go:171","msg":"trace[1383984420] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24123; }","duration":"108.601736ms","start":"2026-10-14T18:36:24.740809Z","end":"2026-10-14T18:36:24.84941Z","steps":["trace[1383984420] 'agreement among raft nodes before linearized reading' (duration: 108.448173ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:37:27.099377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21641} {"level":"info","ts":"2026-10-14T18:37:27.222497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21641,"took":"119.048578ms","hash":1797941554,"current-db-size-bytes":56250368,"current-db-size":"56 MB","current-db-size-in-use-bytes":43098112,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-14T18:37:27.222566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1797941554,"revision":21641,"compact-revision":19645} {"level":"info","ts":"2026-10-14T18:39:50.028761Z","caller":"traceutil/trace.go:171","msg":"trace[1264384158] linearizableReadLoop","detail":"{readStateIndex:26982; appliedIndex:26981; }","duration":"120.736488ms","start":"2026-10-14T18:39:49.907998Z","end":"2026-10-14T18:39:50.028734Z","steps":["trace[1264384158] 'read index received' (duration: 120.521624ms)","trace[1264384158] 'applied index is now lower than readState.Index' (duration: 211.314µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:39:50.029104Z","caller":"traceutil/trace.go:171","msg":"trace[2006539967] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"125.372545ms","start":"2026-10-14T18:39:49.903719Z","end":"2026-10-14T18:39:50.029091Z","steps":["trace[2006539967] 'process raft request' (duration: 124.810685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:39:50.029342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.31191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:39:50.029428Z","caller":"traceutil/trace.go:171","msg":"trace[1812975844] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26117; }","duration":"121.456492ms","start":"2026-10-14T18:39:49.90795Z","end":"2026-10-14T18:39:50.029407Z","steps":["trace[1812975844] 'agreement among raft nodes before linearized reading' (duration: 121.301809ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:39:55.706705Z","caller":"traceutil/trace.go:171","msg":"trace[116304560] linearizableReadLoop","detail":"{readStateIndex:27021; appliedIndex:27020; }","duration":"411.296081ms","start":"2026-10-14T18:39:55.295388Z","end":"2026-10-14T18:39:55.706684Z","steps":["trace[116304560] 'read index received' (duration: 411.215289ms)","trace[116304560] 'applied index is now lower than readState.Index' (duration: 79.692µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:39:55.706809Z","caller":"traceutil/trace.go:171","msg":"trace[469747550] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"460.894772ms","start":"2026-10-14T18:39:55.245903Z","end":"2026-10-14T18:39:55.706798Z","steps":["trace[469747550] 'process raft request' (duration: 460.618007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:39:55.706904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:39:55.245868Z","time spent":"460.961534ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:39:55.706982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.547196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:39:55.707061Z","caller":"traceutil/trace.go:171","msg":"trace[453669800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26155; }","duration":"411.704759ms","start":"2026-10-14T18:39:55.29534Z","end":"2026-10-14T18:39:55.707044Z","steps":["trace[453669800] 'agreement among raft nodes before linearized reading' (duration: 411.552286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:39:55.707128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:39:55.29532Z","time spent":"411.79843ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-14T18:39:55.709793Z","caller":"traceutil/trace.go:171","msg":"trace[308144317] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"314.139375ms","start":"2026-10-14T18:39:55.395638Z","end":"2026-10-14T18:39:55.709777Z","steps":["trace[308144317] 'process raft request' (duration: 313.719457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:39:55.709941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:39:55.395616Z","time spent":"314.220797ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:39:56.255739Z","caller":"traceutil/trace.go:171","msg":"trace[1724381417] linearizableReadLoop","detail":"{readStateIndex:27027; appliedIndex:27026; }","duration":"237.879942ms","start":"2026-10-14T18:39:56.017818Z","end":"2026-10-14T18:39:56.255698Z","steps":["trace[1724381417] 'read index received' (duration: 172.762065ms)","trace[1724381417] 'applied index is now lower than readState.Index' (duration: 65.111857ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:39:56.255849Z","caller":"traceutil/trace.go:171","msg":"trace[1053594677] transaction","detail":"{read_only:false; response_revision:26161; number_of_response:1; }","duration":"206.649128ms","start":"2026-10-14T18:39:56.049153Z","end":"2026-10-14T18:39:56.255802Z","steps":["trace[1053594677] 'process raft request' (duration: 206.444055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:39:56.256149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.295061ms","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-10-14T18:39:56.256214Z","caller":"traceutil/trace.go:171","msg":"trace[1611042257] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26161; }","duration":"238.422973ms","start":"2026-10-14T18:39:56.017774Z","end":"2026-10-14T18:39:56.256197Z","steps":["trace[1611042257] 'agreement among raft nodes before linearized reading' (duration: 238.043776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:39:56.256606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.619241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-14T18:39:56.256612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.757511ms","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-10-14T18:39:56.256762Z","caller":"traceutil/trace.go:171","msg":"trace[611025255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26161; }","duration":"195.002946ms","start":"2026-10-14T18:39:56.061725Z","end":"2026-10-14T18:39:56.256728Z","steps":["trace[611025255] 'agreement among raft nodes before linearized reading' (duration: 194.18442ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:39:56.256689Z","caller":"traceutil/trace.go:171","msg":"trace[2006983425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26161; }","duration":"192.789803ms","start":"2026-10-14T18:39:56.063875Z","end":"2026-10-14T18:39:56.256664Z","steps":["trace[2006983425] 'agreement among raft nodes before linearized reading' (duration: 192.459947ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:39:56.463347Z","caller":"traceutil/trace.go:171","msg":"trace[1625004116] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"201.26155ms","start":"2026-10-14T18:39:56.262048Z","end":"2026-10-14T18:39:56.463309Z","steps":["trace[1625004116] 'process raft request' (duration: 201.155668ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:39:56.463478Z","caller":"traceutil/trace.go:171","msg":"trace[743057590] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"201.979293ms","start":"2026-10-14T18:39:56.261445Z","end":"2026-10-14T18:39:56.463424Z","steps":["trace[743057590] 'process raft request' (duration: 185.063002ms)","trace[743057590] 'compare' (duration: 16.384321ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:39:56.463722Z","caller":"traceutil/trace.go:171","msg":"trace[822334309] linearizableReadLoop","detail":"{readStateIndex:27029; appliedIndex:27028; }","duration":"201.84406ms","start":"2026-10-14T18:39:56.261865Z","end":"2026-10-14T18:39:56.463709Z","steps":["trace[822334309] 'read index received' (duration: 184.654834ms)","trace[822334309] 'applied index is now lower than readState.Index' (duration: 17.187846ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:39:56.463977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.106936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19191"} {"level":"info","ts":"2026-10-14T18:39:56.464012Z","caller":"traceutil/trace.go:171","msg":"trace[1373414904] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26163; }","duration":"202.200777ms","start":"2026-10-14T18:39:56.261802Z","end":"2026-10-14T18:39:56.464003Z","steps":["trace[1373414904] 'agreement among raft nodes before linearized reading' (duration: 202.037474ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:40:10.336993Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a1d.wal"} {"level":"info","ts":"2026-10-14T18:40:52.982355Z","caller":"traceutil/trace.go:171","msg":"trace[1420933804] linearizableReadLoop","detail":"{readStateIndex:27597; appliedIndex:27596; }","duration":"120.422574ms","start":"2026-10-14T18:40:52.861907Z","end":"2026-10-14T18:40:52.98233Z","steps":["trace[1420933804] 'read index received' (duration: 120.2273ms)","trace[1420933804] 'applied index is now lower than readState.Index' (duration: 194.684µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:40:52.982411Z","caller":"traceutil/trace.go:171","msg":"trace[609526377] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"135.361898ms","start":"2026-10-14T18:40:52.847024Z","end":"2026-10-14T18:40:52.982386Z","steps":["trace[609526377] 'process raft request' (duration: 135.153044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:40:52.982583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.633707ms","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-10-14T18:40:52.98262Z","caller":"traceutil/trace.go:171","msg":"trace[380355873] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26717; }","duration":"120.75105ms","start":"2026-10-14T18:40:52.86186Z","end":"2026-10-14T18:40:52.982611Z","steps":["trace[380355873] 'agreement among raft nodes before linearized reading' (duration: 120.570597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:40:58.348476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.159548ms","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-10-14T18:40:58.348606Z","caller":"traceutil/trace.go:171","msg":"trace[177870834] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26755; }","duration":"176.334942ms","start":"2026-10-14T18:40:58.172247Z","end":"2026-10-14T18:40:58.348582Z","steps":["trace[177870834] 'range keys from in-memory index tree' (duration: 176.057426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:40:59.003969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.335416ms","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-10-14T18:40:59.003982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.962471ms","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-10-14T18:40:59.004031Z","caller":"traceutil/trace.go:171","msg":"trace[1529846810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26756; }","duration":"222.430558ms","start":"2026-10-14T18:40:58.781588Z","end":"2026-10-14T18:40:59.004018Z","steps":["trace[1529846810] 'range keys from in-memory index tree' (duration: 222.206664ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:40:59.004042Z","caller":"traceutil/trace.go:171","msg":"trace[343638728] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26756; }","duration":"283.075174ms","start":"2026-10-14T18:40:58.720953Z","end":"2026-10-14T18:40:59.004028Z","steps":["trace[343638728] 'count revisions from in-memory index tree' (duration: 282.88594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:40:59.004202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.739291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-14T18:40:59.004199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.604513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-10-14T18:40:59.004234Z","caller":"traceutil/trace.go:171","msg":"trace[1948802647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26756; }","duration":"175.804261ms","start":"2026-10-14T18:40:58.828418Z","end":"2026-10-14T18:40:59.004223Z","steps":["trace[1948802647] 'range keys from in-memory index tree' (duration: 175.547156ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:40:59.004267Z","caller":"traceutil/trace.go:171","msg":"trace[201961472] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26756; }","duration":"351.749256ms","start":"2026-10-14T18:40:58.652499Z","end":"2026-10-14T18:40:59.004249Z","steps":["trace[201961472] 'range keys from in-memory index tree' (duration: 351.405609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:40:59.004304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:40:58.65248Z","time spent":"351.815837ms","remote":"127.0.0.1:55430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11819,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-10-14T18:40:59.246162Z","caller":"traceutil/trace.go:171","msg":"trace[1334044692] transaction","detail":"{read_only:false; response_revision:26758; number_of_response:1; }","duration":"238.79875ms","start":"2026-10-14T18:40:59.007303Z","end":"2026-10-14T18:40:59.246102Z","steps":["trace[1334044692] 'process raft request' (duration: 238.372912ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:40:59.246286Z","caller":"traceutil/trace.go:171","msg":"trace[1786862391] transaction","detail":"{read_only:false; response_revision:26759; number_of_response:1; }","duration":"238.179769ms","start":"2026-10-14T18:40:59.008073Z","end":"2026-10-14T18:40:59.246253Z","steps":["trace[1786862391] 'process raft request' (duration: 237.927454ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:40:59.274506Z","caller":"traceutil/trace.go:171","msg":"trace[993301683] linearizableReadLoop","detail":"{readStateIndex:27641; appliedIndex:27638; }","duration":"165.032304ms","start":"2026-10-14T18:40:59.109451Z","end":"2026-10-14T18:40:59.274483Z","steps":["trace[993301683] 'read index received' (duration: 136.275395ms)","trace[993301683] 'applied index is now lower than readState.Index' (duration: 28.755789ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:40:59.274565Z","caller":"traceutil/trace.go:171","msg":"trace[1357249791] transaction","detail":"{read_only:false; response_revision:26760; number_of_response:1; }","duration":"231.526757ms","start":"2026-10-14T18:40:59.042987Z","end":"2026-10-14T18:40:59.274514Z","steps":["trace[1357249791] 'process raft request' (duration: 231.360464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:40:59.274778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.288099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T18:40:59.274833Z","caller":"traceutil/trace.go:171","msg":"trace[266178469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26760; }","duration":"165.38309ms","start":"2026-10-14T18:40:59.109433Z","end":"2026-10-14T18:40:59.274816Z","steps":["trace[266178469] 'agreement among raft nodes before linearized reading' (duration: 165.180837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:40:59.275026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.571493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-14T18:40:59.275091Z","caller":"traceutil/trace.go:171","msg":"trace[37748569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26760; }","duration":"165.669254ms","start":"2026-10-14T18:40:59.109411Z","end":"2026-10-14T18:40:59.27508Z","steps":["trace[37748569] 'agreement among raft nodes before linearized reading' (duration: 165.538193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:40:59.692292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.25395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:40:59.692404Z","caller":"traceutil/trace.go:171","msg":"trace[685460792] transaction","detail":"{read_only:false; response_revision:26762; number_of_response:1; }","duration":"412.668065ms","start":"2026-10-14T18:40:59.279716Z","end":"2026-10-14T18:40:59.692384Z","steps":["trace[685460792] 'process raft request' (duration: 211.234283ms)","trace[685460792] 'compare' (duration: 201.128788ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:40:59.692464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:40:59.279684Z","time spent":"412.751867ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:42:27.107105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24809} {"level":"info","ts":"2026-10-14T18:42:27.264462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24809,"took":"153.343669ms","hash":773594174,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":36147200,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T18:42:27.26455Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":773594174,"revision":24809,"compact-revision":21641} {"level":"info","ts":"2026-10-14T18:44:52.476979Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:44:52.486188Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T18:44:52.486308Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T18:47:27.114599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27612} {"level":"info","ts":"2026-10-14T18:47:27.256408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27612,"took":"137.523933ms","hash":764983886,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":34009088,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T18:47:27.256489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":764983886,"revision":27612,"compact-revision":24809} {"level":"info","ts":"2026-10-14T18:52:27.121459Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30274} {"level":"info","ts":"2026-10-14T18:52:27.271727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30274,"took":"146.00441ms","hash":3600653505,"current-db-size-bytes":57516032,"current-db-size":"58 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:52:27.271815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3600653505,"revision":30274,"compact-revision":27612} {"level":"info","ts":"2026-10-14T18:57:27.132427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32530} {"level":"info","ts":"2026-10-14T18:57:27.292531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32530,"took":"155.685582ms","hash":1062589386,"current-db-size-bytes":58560512,"current-db-size":"59 MB","current-db-size-in-use-bytes":30183424,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:57:27.292613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1062589386,"revision":32530,"compact-revision":30274} {"level":"info","ts":"2026-10-14T19:02:27.142735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34759} {"level":"info","ts":"2026-10-14T19:02:27.295832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34759,"took":"147.410715ms","hash":4045586029,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":32362496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T19:02:27.295907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4045586029,"revision":34759,"compact-revision":32530} {"level":"info","ts":"2026-10-14T19:06:01.376562Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T19:06:01.39225Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T19:06:01.392413Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T19:07:27.159304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37120} {"level":"info","ts":"2026-10-14T19:07:27.302436Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37120,"took":"139.192014ms","hash":2746569048,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":33538048,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T19:07:27.302502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2746569048,"revision":37120,"compact-revision":34759} {"level":"info","ts":"2026-10-14T19:12:27.165092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39423} {"level":"info","ts":"2026-10-14T19:12:27.305963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39423,"took":"137.169891ms","hash":3072796422,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":31731712,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T19:12:27.306018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3072796422,"revision":39423,"compact-revision":37120}