{"level":"warn","ts":"2026-04-08T01:19:27.754862Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T01:19:27.754936Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-08T01:19:27.754997Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T01:19:27.755011Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-08T01:19:27.755029Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T01:19:27.755633Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]} {"level":"info","ts":"2026-04-08T01:19:27.755706Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-08T01:19:27.760426Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.5049ms"} {"level":"info","ts":"2026-04-08T01:19:27.771195Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"} {"level":"info","ts":"2026-04-08T01:19:27.77127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T01:19:27.771295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"} {"level":"info","ts":"2026-04-08T01:19:27.771305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T01:19:27.771317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"} {"level":"info","ts":"2026-04-08T01:19:27.771351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"warn","ts":"2026-04-08T01:19:27.778288Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T01:19:27.781662Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T01:19:27.783192Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-08T01:19:27.786406Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T01:19:27.786608Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T01:19:27.78664Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T01:19:27.786785Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T01:19:27.78681Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T01:19:27.789264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"info","ts":"2026-04-08T01:19:27.789561Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-08T01:19:27.79245Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T01:19:27.792588Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-08T01:19:27.792638Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-08T01:19:27.792911Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T01:19:27.792985Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T01:19:28.372475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T01:19:28.372548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T01:19:28.372608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"} {"level":"info","ts":"2026-04-08T01:19:28.372634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"} {"level":"info","ts":"2026-04-08T01:19:28.372645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-08T01:19:28.372661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"} {"level":"info","ts":"2026-04-08T01:19:28.372673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-08T01:19:28.373837Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T01:19:28.37407Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T01:19:28.374111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T01:19:28.374218Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:19:28.375282Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:19:28.375405Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:19:28.375444Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T01:19:28.376538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T01:19:28.3779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"} {"level":"info","ts":"2026-04-08T01:19:28.392276Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T01:19:28.392327Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-08T01:19:50.059991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.722268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T01:19:50.060191Z","caller":"traceutil/trace.go:171","msg":"trace[1415977713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"130.038756ms","start":"2026-04-08T01:19:49.930124Z","end":"2026-04-08T01:19:50.060163Z","steps":["trace[1415977713] 'range keys from in-memory index tree' (duration: 129.508342ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:20:09.191603Z","caller":"traceutil/trace.go:171","msg":"trace[2075511203] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"106.676595ms","start":"2026-04-08T01:20:09.084893Z","end":"2026-04-08T01:20:09.19157Z","steps":["trace[2075511203] 'read index received' (duration: 105.77375ms)","trace[2075511203] 'applied index is now lower than readState.Index' (duration: 901.625µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:20:09.191743Z","caller":"traceutil/trace.go:171","msg":"trace[941567939] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"133.787565ms","start":"2026-04-08T01:20:09.057897Z","end":"2026-04-08T01:20:09.191684Z","steps":["trace[941567939] 'process raft request' (duration: 133.067145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:20:09.191874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.946612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-08T01:20:09.192013Z","caller":"traceutil/trace.go:171","msg":"trace[1069620866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"107.149637ms","start":"2026-04-08T01:20:09.084837Z","end":"2026-04-08T01:20:09.191987Z","steps":["trace[1069620866] 'agreement among raft nodes before linearized reading' (duration: 106.848609ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:20:09.3849Z","caller":"traceutil/trace.go:171","msg":"trace[2133659496] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"137.535436ms","start":"2026-04-08T01:20:09.247322Z","end":"2026-04-08T01:20:09.384858Z","steps":["trace[2133659496] 'read index received' (duration: 58.600208ms)","trace[2133659496] 'applied index is now lower than readState.Index' (duration: 78.933998ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:20:09.384955Z","caller":"traceutil/trace.go:171","msg":"trace[333290223] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"187.608476ms","start":"2026-04-08T01:20:09.197308Z","end":"2026-04-08T01:20:09.384917Z","steps":["trace[333290223] 'process raft request' (duration: 108.601706ms)","trace[333290223] 'compare' (duration: 78.823875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:20:09.385091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.74631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:20:09.385138Z","caller":"traceutil/trace.go:171","msg":"trace[585930962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"137.880233ms","start":"2026-04-08T01:20:09.247247Z","end":"2026-04-08T01:20:09.385127Z","steps":["trace[585930962] 'agreement among raft nodes before linearized reading' (duration: 137.76272ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:20:09.407704Z","caller":"traceutil/trace.go:171","msg":"trace[1205637341] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"158.361728ms","start":"2026-04-08T01:20:09.249311Z","end":"2026-04-08T01:20:09.407672Z","steps":["trace[1205637341] 'process raft request' (duration: 158.154963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:20:10.056887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.242397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:20:10.057245Z","caller":"traceutil/trace.go:171","msg":"trace[952561346] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"634.796631ms","start":"2026-04-08T01:20:09.422407Z","end":"2026-04-08T01:20:10.057204Z","steps":["trace[952561346] 'process raft request' (duration: 350.664519ms)","trace[952561346] 'compare' (duration: 283.071013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:20:10.057516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:20:09.422372Z","time spent":"634.962505ms","remote":"127.0.0.1:35994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:20:10.059407Z","caller":"traceutil/trace.go:171","msg":"trace[45449362] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"147.931681ms","start":"2026-04-08T01:20:09.911457Z","end":"2026-04-08T01:20:10.059389Z","steps":["trace[45449362] 'read index received' (duration: 143.502924ms)","trace[45449362] 'applied index is now lower than readState.Index' (duration: 4.427647ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:20:10.059571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.115876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T01:20:10.059608Z","caller":"traceutil/trace.go:171","msg":"trace[756969337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"148.215319ms","start":"2026-04-08T01:20:09.911384Z","end":"2026-04-08T01:20:10.0596Z","steps":["trace[756969337] 'agreement among raft nodes before linearized reading' (duration: 148.070466ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:20:10.059846Z","caller":"traceutil/trace.go:171","msg":"trace[1860367184] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"231.532603ms","start":"2026-04-08T01:20:09.828249Z","end":"2026-04-08T01:20:10.059782Z","steps":["trace[1860367184] 'process raft request' (duration: 229.462677ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:20:50.566092Z","caller":"traceutil/trace.go:171","msg":"trace[1303905543] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"114.801464ms","start":"2026-04-08T01:20:50.451248Z","end":"2026-04-08T01:20:50.566049Z","steps":["trace[1303905543] 'process raft request' (duration: 114.561298ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:20:50.745544Z","caller":"traceutil/trace.go:171","msg":"trace[154356738] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"174.935056ms","start":"2026-04-08T01:20:50.570586Z","end":"2026-04-08T01:20:50.745521Z","steps":["trace[154356738] 'process raft request' (duration: 173.613881ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:21:32.907364Z","caller":"traceutil/trace.go:171","msg":"trace[120686066] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"133.377927ms","start":"2026-04-08T01:21:32.773943Z","end":"2026-04-08T01:21:32.907321Z","steps":["trace[120686066] 'process raft request' (duration: 62.069619ms)","trace[120686066] 'compare' (duration: 70.898347ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:21:33.174106Z","caller":"traceutil/trace.go:171","msg":"trace[723790987] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"291.442394ms","start":"2026-04-08T01:21:32.882632Z","end":"2026-04-08T01:21:33.174074Z","steps":["trace[723790987] 'process raft request' (duration: 283.166009ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:21:33.228525Z","caller":"traceutil/trace.go:171","msg":"trace[651315957] linearizableReadLoop","detail":"{readStateIndex:1846; appliedIndex:1844; }","duration":"233.540013ms","start":"2026-04-08T01:21:32.99496Z","end":"2026-04-08T01:21:33.2285Z","steps":["trace[651315957] 'read index received' (duration: 170.812027ms)","trace[651315957] 'applied index is now lower than readState.Index' (duration: 62.726936ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:21:33.22862Z","caller":"traceutil/trace.go:171","msg":"trace[401245409] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"312.94762ms","start":"2026-04-08T01:21:32.915647Z","end":"2026-04-08T01:21:33.228594Z","steps":["trace[401245409] 'process raft request' (duration: 312.643043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:21:33.228779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.813601ms","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-04-08T01:21:33.228826Z","caller":"traceutil/trace.go:171","msg":"trace[1084145337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1776; }","duration":"233.914373ms","start":"2026-04-08T01:21:32.994901Z","end":"2026-04-08T01:21:33.228815Z","steps":["trace[1084145337] 'agreement among raft nodes before linearized reading' (duration: 233.735589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:21:33.22883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:21:32.915613Z","time spent":"313.055054ms","remote":"127.0.0.1:35994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3727,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:21:42.545356Z","caller":"traceutil/trace.go:171","msg":"trace[1720654157] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"124.303202ms","start":"2026-04-08T01:21:42.421002Z","end":"2026-04-08T01:21:42.545305Z","steps":["trace[1720654157] 'process raft request' (duration: 124.076506ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:23.531386Z","caller":"traceutil/trace.go:171","msg":"trace[400508263] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"258.225029ms","start":"2026-04-08T01:22:23.273134Z","end":"2026-04-08T01:22:23.531359Z","steps":["trace[400508263] 'process raft request' (duration: 258.051405ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:22:23.536402Z","caller":"traceutil/trace.go:171","msg":"trace[861575114] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"118.560304ms","start":"2026-04-08T01:22:23.417818Z","end":"2026-04-08T01:22:23.536379Z","steps":["trace[861575114] 'process raft request' (duration: 118.396829ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:13.052328Z","caller":"traceutil/trace.go:171","msg":"trace[1899535453] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"152.009988ms","start":"2026-04-08T01:23:12.900229Z","end":"2026-04-08T01:23:13.052239Z","steps":["trace[1899535453] 'process raft request' (duration: 151.864994ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:13.08812Z","caller":"traceutil/trace.go:171","msg":"trace[979463257] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"127.501476ms","start":"2026-04-08T01:23:12.960582Z","end":"2026-04-08T01:23:13.088084Z","steps":["trace[979463257] 'process raft request' (duration: 127.192798ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:46.149278Z","caller":"traceutil/trace.go:171","msg":"trace[1233555030] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"213.257651ms","start":"2026-04-08T01:23:45.93598Z","end":"2026-04-08T01:23:46.149238Z","steps":["trace[1233555030] 'process raft request' (duration: 134.64327ms)","trace[1233555030] 'compare' (duration: 78.435716ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:23:46.149626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.498971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T01:23:46.149706Z","caller":"traceutil/trace.go:171","msg":"trace[996566247] linearizableReadLoop","detail":"{readStateIndex:2852; appliedIndex:2851; }","duration":"155.604224ms","start":"2026-04-08T01:23:45.994087Z","end":"2026-04-08T01:23:46.149691Z","steps":["trace[996566247] 'read index received' (duration: 76.415844ms)","trace[996566247] 'applied index is now lower than readState.Index' (duration: 78.682584ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:23:46.149724Z","caller":"traceutil/trace.go:171","msg":"trace[430214474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2743; }","duration":"155.665568ms","start":"2026-04-08T01:23:45.994035Z","end":"2026-04-08T01:23:46.149701Z","steps":["trace[430214474] 'agreement among raft nodes before linearized reading' (duration: 155.337996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:23:46.149904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.525308ms","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-04-08T01:23:46.149946Z","caller":"traceutil/trace.go:171","msg":"trace[1566486700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2743; }","duration":"144.623531ms","start":"2026-04-08T01:23:46.005312Z","end":"2026-04-08T01:23:46.149936Z","steps":["trace[1566486700] 'agreement among raft nodes before linearized reading' (duration: 144.482676ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:23:46.34065Z","caller":"traceutil/trace.go:171","msg":"trace[572310840] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"186.25847ms","start":"2026-04-08T01:23:46.15437Z","end":"2026-04-08T01:23:46.340628Z","steps":["trace[572310840] 'process raft request' (duration: 166.763496ms)","trace[572310840] 'compare' (duration: 19.239866ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:23:46.340984Z","caller":"traceutil/trace.go:171","msg":"trace[2122016312] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"186.474347ms","start":"2026-04-08T01:23:46.154474Z","end":"2026-04-08T01:23:46.340948Z","steps":["trace[2122016312] 'process raft request' (duration: 186.102385ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:27:42.967264Z","caller":"traceutil/trace.go:171","msg":"trace[1736360498] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"173.289573ms","start":"2026-04-08T01:27:42.793945Z","end":"2026-04-08T01:27:42.967234Z","steps":["trace[1736360498] 'process raft request' (duration: 173.221251ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:27:42.967427Z","caller":"traceutil/trace.go:171","msg":"trace[1416841640] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"177.287797ms","start":"2026-04-08T01:27:42.79011Z","end":"2026-04-08T01:27:42.967398Z","steps":["trace[1416841640] 'process raft request' (duration: 160.787212ms)","trace[1416841640] 'compare' (duration: 16.032313ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:27:48.14553Z","caller":"traceutil/trace.go:171","msg":"trace[78307419] linearizableReadLoop","detail":"{readStateIndex:5009; appliedIndex:5008; }","duration":"117.229178ms","start":"2026-04-08T01:27:48.028275Z","end":"2026-04-08T01:27:48.145504Z","steps":["trace[78307419] 'read index received' (duration: 117.103235ms)","trace[78307419] 'applied index is now lower than readState.Index' (duration: 124.493µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:27:48.145723Z","caller":"traceutil/trace.go:171","msg":"trace[1502991522] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"118.143063ms","start":"2026-04-08T01:27:48.027517Z","end":"2026-04-08T01:27:48.14566Z","steps":["trace[1502991522] 'process raft request' (duration: 117.790104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:27:48.146016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.710571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-08T01:27:48.146105Z","caller":"traceutil/trace.go:171","msg":"trace[1825836116] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4817; }","duration":"117.836775ms","start":"2026-04-08T01:27:48.028255Z","end":"2026-04-08T01:27:48.146092Z","steps":["trace[1825836116] 'agreement among raft nodes before linearized reading' (duration: 117.421464ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:28:10.335082Z","caller":"traceutil/trace.go:171","msg":"trace[108741863] linearizableReadLoop","detail":"{readStateIndex:5557; appliedIndex:5556; }","duration":"127.995273ms","start":"2026-04-08T01:28:10.207063Z","end":"2026-04-08T01:28:10.335058Z","steps":["trace[108741863] 'read index received' (duration: 127.796977ms)","trace[108741863] 'applied index is now lower than readState.Index' (duration: 196.996µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:28:10.335244Z","caller":"traceutil/trace.go:171","msg":"trace[540688430] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"231.358126ms","start":"2026-04-08T01:28:10.103836Z","end":"2026-04-08T01:28:10.335194Z","steps":["trace[540688430] 'process raft request' (duration: 231.044428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:28:10.335483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.24268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cebdf295-9f97-492e-b2c1-89547f2bf0ac\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-04-08T01:28:10.335746Z","caller":"traceutil/trace.go:171","msg":"trace[1740455829] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cebdf295-9f97-492e-b2c1-89547f2bf0ac; range_end:; response_count:1; response_revision:5349; }","duration":"128.584259ms","start":"2026-04-08T01:28:10.206981Z","end":"2026-04-08T01:28:10.335565Z","steps":["trace[1740455829] 'agreement among raft nodes before linearized reading' (duration: 128.215029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:28:10.665893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.718986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:28:10.666039Z","caller":"traceutil/trace.go:171","msg":"trace[658839529] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5349; }","duration":"242.929351ms","start":"2026-04-08T01:28:10.42308Z","end":"2026-04-08T01:28:10.66601Z","steps":["trace[658839529] 'count revisions from in-memory index tree' (duration: 242.582863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:28:10.666036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.196713ms","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-04-08T01:28:10.66614Z","caller":"traceutil/trace.go:171","msg":"trace[530780312] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5349; }","duration":"277.330878ms","start":"2026-04-08T01:28:10.388787Z","end":"2026-04-08T01:28:10.666118Z","steps":["trace[530780312] 'range keys from in-memory index tree' (duration: 276.968008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:28:10.666427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.044071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:28:10.667007Z","caller":"traceutil/trace.go:171","msg":"trace[1529627635] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"242.663065ms","start":"2026-04-08T01:28:10.424318Z","end":"2026-04-08T01:28:10.666981Z","steps":["trace[1529627635] 'process raft request' (duration: 115.951525ms)","trace[1529627635] 'compare' (duration: 125.483267ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:28:10.706499Z","caller":"traceutil/trace.go:171","msg":"trace[1051619537] linearizableReadLoop","detail":"{readStateIndex:5559; appliedIndex:5557; }","duration":"250.86388ms","start":"2026-04-08T01:28:10.455609Z","end":"2026-04-08T01:28:10.706473Z","steps":["trace[1051619537] 'read index received' (duration: 84.593318ms)","trace[1051619537] 'applied index is now lower than readState.Index' (duration: 166.269222ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:28:10.706625Z","caller":"traceutil/trace.go:171","msg":"trace[1555847619] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"267.322634ms","start":"2026-04-08T01:28:10.439267Z","end":"2026-04-08T01:28:10.70659Z","steps":["trace[1555847619] 'process raft request' (duration: 267.057567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:28:10.706809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.175808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T01:28:10.707188Z","caller":"traceutil/trace.go:171","msg":"trace[1723880] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5351; }","duration":"251.589789ms","start":"2026-04-08T01:28:10.455569Z","end":"2026-04-08T01:28:10.707158Z","steps":["trace[1723880] 'agreement among raft nodes before linearized reading' (duration: 251.058535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:28:21.627076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.778061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-08T01:28:21.627182Z","caller":"traceutil/trace.go:171","msg":"trace[696388744] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:5516; }","duration":"104.906335ms","start":"2026-04-08T01:28:21.522253Z","end":"2026-04-08T01:28:21.627159Z","steps":["trace[696388744] 'range keys from in-memory index tree' (duration: 104.547165ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:29:28.409441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-04-08T01:29:28.482962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"71.752433ms","hash":1708892618,"current-db-size-bytes":58290176,"current-db-size":"58 MB","current-db-size-in-use-bytes":33607680,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-08T01:29:28.483034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1708892618,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-04-08T01:32:52.60432Z","caller":"traceutil/trace.go:171","msg":"trace[1632826111] linearizableReadLoop","detail":"{readStateIndex:7786; appliedIndex:7785; }","duration":"210.112792ms","start":"2026-04-08T01:32:52.394183Z","end":"2026-04-08T01:32:52.604296Z","steps":["trace[1632826111] 'read index received' (duration: 130.672454ms)","trace[1632826111] 'applied index is now lower than readState.Index' (duration: 79.439218ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:32:52.604505Z","caller":"traceutil/trace.go:171","msg":"trace[106005285] transaction","detail":"{read_only:false; response_revision:7511; number_of_response:1; }","duration":"210.323697ms","start":"2026-04-08T01:32:52.39411Z","end":"2026-04-08T01:32:52.604434Z","steps":["trace[106005285] 'process raft request' (duration: 130.725564ms)","trace[106005285] 'compare' (duration: 79.348256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:32:52.604752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.547503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjlvx\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-04-08T01:32:52.604809Z","caller":"traceutil/trace.go:171","msg":"trace[203154699] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjlvx; range_end:; response_count:1; response_revision:7511; }","duration":"210.656736ms","start":"2026-04-08T01:32:52.394138Z","end":"2026-04-08T01:32:52.604795Z","steps":["trace[203154699] 'agreement among raft nodes before linearized reading' (duration: 210.295197ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:06.786868Z","caller":"traceutil/trace.go:171","msg":"trace[116506474] linearizableReadLoop","detail":"{readStateIndex:8427; appliedIndex:8426; }","duration":"156.419153ms","start":"2026-04-08T01:34:06.630422Z","end":"2026-04-08T01:34:06.786842Z","steps":["trace[116506474] 'read index received' (duration: 156.249209ms)","trace[116506474] 'applied index is now lower than readState.Index' (duration: 168.244µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:34:06.786905Z","caller":"traceutil/trace.go:171","msg":"trace[1794993705] transaction","detail":"{read_only:false; response_revision:8135; number_of_response:1; }","duration":"269.849588ms","start":"2026-04-08T01:34:06.517026Z","end":"2026-04-08T01:34:06.786876Z","steps":["trace[1794993705] 'process raft request' (duration: 269.657593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:06.787116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.649169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:34:06.78722Z","caller":"traceutil/trace.go:171","msg":"trace[1934034356] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:8135; }","duration":"156.833375ms","start":"2026-04-08T01:34:06.630368Z","end":"2026-04-08T01:34:06.787201Z","steps":["trace[1934034356] 'agreement among raft nodes before linearized reading' (duration: 156.602818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:18.461318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.736641ms","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-04-08T01:34:18.461448Z","caller":"traceutil/trace.go:171","msg":"trace[1477622979] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8196; }","duration":"358.917536ms","start":"2026-04-08T01:34:18.102506Z","end":"2026-04-08T01:34:18.461424Z","steps":["trace[1477622979] 'range keys from in-memory index tree' (duration: 358.483744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:18.461495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:34:18.102486Z","time spent":"358.998777ms","remote":"127.0.0.1:35994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-08T01:34:18.461678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.353869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:34:18.461889Z","caller":"traceutil/trace.go:171","msg":"trace[127241018] linearizableReadLoop","detail":"{readStateIndex:8493; appliedIndex:8492; }","duration":"213.390859ms","start":"2026-04-08T01:34:18.248476Z","end":"2026-04-08T01:34:18.461867Z","steps":["trace[127241018] 'read index received' (duration: 96.852395ms)","trace[127241018] 'applied index is now lower than readState.Index' (duration: 116.537214ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:34:18.461978Z","caller":"traceutil/trace.go:171","msg":"trace[1502067069] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"320.262197ms","start":"2026-04-08T01:34:18.141612Z","end":"2026-04-08T01:34:18.461874Z","steps":["trace[1502067069] 'process raft request' (duration: 203.581269ms)","trace[1502067069] 'compare' (duration: 116.191634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:34:18.462324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:34:18.141582Z","time spent":"320.542223ms","remote":"127.0.0.1:36060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:34:18.462327Z","caller":"traceutil/trace.go:171","msg":"trace[230613505] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"150.248444ms","start":"2026-04-08T01:34:18.312031Z","end":"2026-04-08T01:34:18.462279Z","steps":["trace[230613505] 'process raft request' (duration: 149.781971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:18.462336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.84322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T01:34:18.462443Z","caller":"traceutil/trace.go:171","msg":"trace[1589306140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8198; }","duration":"214.049416ms","start":"2026-04-08T01:34:18.248365Z","end":"2026-04-08T01:34:18.462415Z","steps":["trace[1589306140] 'agreement among raft nodes before linearized reading' (duration: 213.559553ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:18.462442Z","caller":"traceutil/trace.go:171","msg":"trace[1115427079] transaction","detail":"{read_only:false; response_revision:8199; number_of_response:1; }","duration":"147.533621ms","start":"2026-04-08T01:34:18.314866Z","end":"2026-04-08T01:34:18.4624Z","steps":["trace[1115427079] 'process raft request' (duration: 147.304525ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:19.456232Z","caller":"traceutil/trace.go:171","msg":"trace[1378383250] linearizableReadLoop","detail":"{readStateIndex:8498; appliedIndex:8497; }","duration":"317.634618ms","start":"2026-04-08T01:34:19.13857Z","end":"2026-04-08T01:34:19.456205Z","steps":["trace[1378383250] 'read index received' (duration: 317.276007ms)","trace[1378383250] 'applied index is now lower than readState.Index' (duration: 357.141µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:34:19.456294Z","caller":"traceutil/trace.go:171","msg":"trace[1761993502] transaction","detail":"{read_only:false; response_revision:8202; number_of_response:1; }","duration":"338.286211ms","start":"2026-04-08T01:34:19.117972Z","end":"2026-04-08T01:34:19.456258Z","steps":["trace[1761993502] 'process raft request' (duration: 337.958502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:19.456478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:34:19.117955Z","time spent":"338.423335ms","remote":"127.0.0.1:36060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T01:34:19.456514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.90312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjlvx\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-04-08T01:34:19.456556Z","caller":"traceutil/trace.go:171","msg":"trace[1455443438] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjlvx; range_end:; response_count:1; response_revision:8202; }","duration":"315.986783ms","start":"2026-04-08T01:34:19.140554Z","end":"2026-04-08T01:34:19.456541Z","steps":["trace[1455443438] 'agreement among raft nodes before linearized reading' (duration: 315.815068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:19.456607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:34:19.140526Z","time spent":"316.056385ms","remote":"127.0.0.1:35994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-sjlvx\" "} {"level":"warn","ts":"2026-04-08T01:34:19.456611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.997176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T01:34:19.456736Z","caller":"traceutil/trace.go:171","msg":"trace[335620278] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8202; }","duration":"318.16123ms","start":"2026-04-08T01:34:19.138549Z","end":"2026-04-08T01:34:19.456711Z","steps":["trace[335620278] 'agreement among raft nodes before linearized reading' (duration: 317.811221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:19.456652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.796884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T01:34:19.456806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:34:19.138463Z","time spent":"318.330385ms","remote":"127.0.0.1:36002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":111,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-08T01:34:19.456902Z","caller":"traceutil/trace.go:171","msg":"trace[1773995732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8202; }","duration":"207.154803ms","start":"2026-04-08T01:34:19.249721Z","end":"2026-04-08T01:34:19.456876Z","steps":["trace[1773995732] 'agreement among raft nodes before linearized reading' (duration: 206.713462ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:20.064701Z","caller":"traceutil/trace.go:171","msg":"trace[896023721] transaction","detail":"{read_only:false; response_revision:8204; number_of_response:1; }","duration":"110.615697ms","start":"2026-04-08T01:34:19.954038Z","end":"2026-04-08T01:34:20.064653Z","steps":["trace[896023721] 'process raft request' (duration: 110.365681ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:20.187552Z","caller":"traceutil/trace.go:171","msg":"trace[2108576715] transaction","detail":"{read_only:false; response_revision:8206; number_of_response:1; }","duration":"117.310164ms","start":"2026-04-08T01:34:20.070213Z","end":"2026-04-08T01:34:20.187523Z","steps":["trace[2108576715] 'process raft request' (duration: 117.16185ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:20.187926Z","caller":"traceutil/trace.go:171","msg":"trace[148457734] transaction","detail":"{read_only:false; response_revision:8207; number_of_response:1; }","duration":"117.219921ms","start":"2026-04-08T01:34:20.07069Z","end":"2026-04-08T01:34:20.187909Z","steps":["trace[148457734] 'process raft request' (duration: 116.78429ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:20.18823Z","caller":"traceutil/trace.go:171","msg":"trace[794372040] transaction","detail":"{read_only:false; response_revision:8208; number_of_response:1; }","duration":"116.954124ms","start":"2026-04-08T01:34:20.071242Z","end":"2026-04-08T01:34:20.188196Z","steps":["trace[794372040] 'process raft request' (duration: 116.542513ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:28.41694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6074} {"level":"info","ts":"2026-04-08T01:34:28.514154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6074,"took":"94.862263ms","hash":4229695081,"current-db-size-bytes":58290176,"current-db-size":"58 MB","current-db-size-in-use-bytes":23289856,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-08T01:34:28.514226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4229695081,"revision":6074,"compact-revision":2974} {"level":"info","ts":"2026-04-08T01:34:47.170974Z","caller":"traceutil/trace.go:171","msg":"trace[1662926758] transaction","detail":"{read_only:false; response_revision:8403; number_of_response:1; }","duration":"577.741497ms","start":"2026-04-08T01:34:46.593169Z","end":"2026-04-08T01:34:47.17091Z","steps":["trace[1662926758] 'process raft request' (duration: 577.411138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:47.171219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:34:46.593135Z","time spent":"577.955452ms","remote":"127.0.0.1:36060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:34:47.172766Z","caller":"traceutil/trace.go:171","msg":"trace[46644372] transaction","detail":"{read_only:false; response_revision:8404; number_of_response:1; }","duration":"575.666211ms","start":"2026-04-08T01:34:46.597046Z","end":"2026-04-08T01:34:47.172712Z","steps":["trace[46644372] 'process raft request' (duration: 575.21709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:47.172968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:34:46.597024Z","time spent":"575.827786ms","remote":"127.0.0.1:36060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:34:47.174947Z","caller":"traceutil/trace.go:171","msg":"trace[1312369483] linearizableReadLoop","detail":"{readStateIndex:8710; appliedIndex:8706; }","duration":"133.47273ms","start":"2026-04-08T01:34:47.041436Z","end":"2026-04-08T01:34:47.174909Z","steps":["trace[1312369483] 'read index received' (duration: 129.138196ms)","trace[1312369483] 'applied index is now lower than readState.Index' (duration: 4.332784ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:34:47.174983Z","caller":"traceutil/trace.go:171","msg":"trace[1593857002] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"169.095749ms","start":"2026-04-08T01:34:47.005835Z","end":"2026-04-08T01:34:47.174931Z","steps":["trace[1593857002] 'process raft request' (duration: 168.885574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:47.175132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.679566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T01:34:47.175161Z","caller":"traceutil/trace.go:171","msg":"trace[1089465269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8405; }","duration":"133.767217ms","start":"2026-04-08T01:34:47.041386Z","end":"2026-04-08T01:34:47.175153Z","steps":["trace[1089465269] 'agreement among raft nodes before linearized reading' (duration: 133.617744ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:47.755068Z","caller":"traceutil/trace.go:171","msg":"trace[798308671] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"127.666048ms","start":"2026-04-08T01:34:47.627376Z","end":"2026-04-08T01:34:47.755042Z","steps":["trace[798308671] 'process raft request' (duration: 127.507203ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:47.755138Z","caller":"traceutil/trace.go:171","msg":"trace[782410615] linearizableReadLoop","detail":"{readStateIndex:8712; appliedIndex:8712; }","duration":"108.928112ms","start":"2026-04-08T01:34:47.646182Z","end":"2026-04-08T01:34:47.75511Z","steps":["trace[782410615] 'read index received' (duration: 108.918152ms)","trace[782410615] 'applied index is now lower than readState.Index' (duration: 7.93µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:34:47.755398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.17558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjlvx\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-04-08T01:34:47.755438Z","caller":"traceutil/trace.go:171","msg":"trace[1333961545] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjlvx; range_end:; response_count:1; response_revision:8407; }","duration":"109.295652ms","start":"2026-04-08T01:34:47.646131Z","end":"2026-04-08T01:34:47.755427Z","steps":["trace[1333961545] 'agreement among raft nodes before linearized reading' (duration: 109.085607ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:59.626886Z","caller":"traceutil/trace.go:171","msg":"trace[1460313109] transaction","detail":"{read_only:false; response_revision:8510; number_of_response:1; }","duration":"113.156165ms","start":"2026-04-08T01:34:59.513699Z","end":"2026-04-08T01:34:59.626855Z","steps":["trace[1460313109] 'process raft request' (duration: 112.99057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:59.967024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.134581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T01:34:59.967285Z","caller":"traceutil/trace.go:171","msg":"trace[526353472] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"327.952449ms","start":"2026-04-08T01:34:59.639177Z","end":"2026-04-08T01:34:59.96713Z","steps":["trace[526353472] 'process raft request' (duration: 169.622774ms)","trace[526353472] 'compare' (duration: 157.869473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:34:59.967388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:34:59.639166Z","time spent":"328.149154ms","remote":"127.0.0.1:35994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4628,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T01:34:59.978283Z","caller":"traceutil/trace.go:171","msg":"trace[2071519541] linearizableReadLoop","detail":"{readStateIndex:8822; appliedIndex:8820; }","duration":"247.682562ms","start":"2026-04-08T01:34:59.730579Z","end":"2026-04-08T01:34:59.978261Z","steps":["trace[2071519541] 'read index received' (duration: 78.14829ms)","trace[2071519541] 'applied index is now lower than readState.Index' (duration: 169.533171ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:34:59.978589Z","caller":"traceutil/trace.go:171","msg":"trace[327653935] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8512; }","duration":"338.64429ms","start":"2026-04-08T01:34:59.639916Z","end":"2026-04-08T01:34:59.978561Z","steps":["trace[327653935] 'process raft request' (duration: 338.169758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:59.978854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.273467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-gxkjp\" ","response":"range_response_count:1 size:4643"} {"level":"warn","ts":"2026-04-08T01:34:59.978877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T01:34:59.639906Z","time spent":"338.717433ms","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-04-08T01:34:59.978889Z","caller":"traceutil/trace.go:171","msg":"trace[81666609] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-gxkjp; range_end:; response_count:1; response_revision:8513; }","duration":"248.337469ms","start":"2026-04-08T01:34:59.730543Z","end":"2026-04-08T01:34:59.97888Z","steps":["trace[81666609] 'agreement among raft nodes before linearized reading' (duration: 248.278058ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:34:59.979178Z","caller":"traceutil/trace.go:171","msg":"trace[1484730847] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"247.445386ms","start":"2026-04-08T01:34:59.731725Z","end":"2026-04-08T01:34:59.97917Z","steps":["trace[1484730847] 'process raft request' (duration: 246.47875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:59.979386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.23744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T01:34:59.979421Z","caller":"traceutil/trace.go:171","msg":"trace[1649079606] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8513; }","duration":"159.316771ms","start":"2026-04-08T01:34:59.820094Z","end":"2026-04-08T01:34:59.979411Z","steps":["trace[1649079606] 'agreement among raft nodes before linearized reading' (duration: 159.014813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:34:59.979946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.073494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjlvx\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-04-08T01:34:59.97999Z","caller":"traceutil/trace.go:171","msg":"trace[98635729] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjlvx; range_end:; response_count:1; response_revision:8513; }","duration":"154.364631ms","start":"2026-04-08T01:34:59.825617Z","end":"2026-04-08T01:34:59.979982Z","steps":["trace[98635729] 'agreement among raft nodes before linearized reading' (duration: 154.006422ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:35:00.645901Z","caller":"traceutil/trace.go:171","msg":"trace[792060768] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"135.77982ms","start":"2026-04-08T01:35:00.510079Z","end":"2026-04-08T01:35:00.645859Z","steps":["trace[792060768] 'process raft request' (duration: 135.487063ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:35:00.849735Z","caller":"traceutil/trace.go:171","msg":"trace[690764879] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"187.460763ms","start":"2026-04-08T01:35:00.662238Z","end":"2026-04-08T01:35:00.849699Z","steps":["trace[690764879] 'process raft request' (duration: 187.168196ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:35:47.920411Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002466.wal"} {"level":"info","ts":"2026-04-08T01:36:10.904461Z","caller":"traceutil/trace.go:171","msg":"trace[453449932] transaction","detail":"{read_only:false; response_revision:9172; number_of_response:1; }","duration":"150.078198ms","start":"2026-04-08T01:36:10.754338Z","end":"2026-04-08T01:36:10.904416Z","steps":["trace[453449932] 'process raft request' (duration: 130.323197ms)","trace[453449932] 'compare' (duration: 19.576166ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:36:10.904473Z","caller":"traceutil/trace.go:171","msg":"trace[1009228463] transaction","detail":"{read_only:false; response_revision:9173; number_of_response:1; }","duration":"149.446221ms","start":"2026-04-08T01:36:10.75498Z","end":"2026-04-08T01:36:10.904426Z","steps":["trace[1009228463] 'process raft request' (duration: 149.373899ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:37:03.186136Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4ec288cbdba84d36","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T01:37:03.19157Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T01:37:03.191687Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T01:39:28.422181Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8254} {"level":"info","ts":"2026-04-08T01:39:28.495169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8254,"took":"70.565043ms","hash":2923715883,"current-db-size-bytes":58290176,"current-db-size":"58 MB","current-db-size-in-use-bytes":25010176,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-08T01:39:28.495243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2923715883,"revision":8254,"compact-revision":6074} {"level":"info","ts":"2026-04-08T01:44:28.428847Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10678} {"level":"info","ts":"2026-04-08T01:44:28.511014Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10678,"took":"78.929735ms","hash":3519482654,"current-db-size-bytes":58290176,"current-db-size":"58 MB","current-db-size-in-use-bytes":20193280,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T01:44:28.511065Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3519482654,"revision":10678,"compact-revision":8254} {"level":"info","ts":"2026-04-08T01:45:02.515179Z","caller":"traceutil/trace.go:171","msg":"trace[1332217819] transaction","detail":"{read_only:false; response_revision:12407; number_of_response:1; }","duration":"110.356099ms","start":"2026-04-08T01:45:02.404775Z","end":"2026-04-08T01:45:02.515131Z","steps":["trace[1332217819] 'process raft request' (duration: 64.745922ms)","trace[1332217819] 'compare' (duration: 45.399322ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:45:05.750183Z","caller":"traceutil/trace.go:171","msg":"trace[1123803202] transaction","detail":"{read_only:false; response_revision:12418; number_of_response:1; }","duration":"128.533189ms","start":"2026-04-08T01:45:05.621608Z","end":"2026-04-08T01:45:05.750141Z","steps":["trace[1123803202] 'process raft request' (duration: 126.446224ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:45:05.753132Z","caller":"traceutil/trace.go:171","msg":"trace[102303202] linearizableReadLoop","detail":"{readStateIndex:12870; appliedIndex:12868; }","duration":"131.073167ms","start":"2026-04-08T01:45:05.622039Z","end":"2026-04-08T01:45:05.753113Z","steps":["trace[102303202] 'read index received' (duration: 126.063174ms)","trace[102303202] 'applied index is now lower than readState.Index' (duration: 5.009063ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:45:05.75327Z","caller":"traceutil/trace.go:171","msg":"trace[191079756] transaction","detail":"{read_only:false; response_revision:12419; number_of_response:1; }","duration":"131.480378ms","start":"2026-04-08T01:45:05.621736Z","end":"2026-04-08T01:45:05.753217Z","steps":["trace[191079756] 'process raft request' (duration: 131.279022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:45:05.75342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.347483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-v86dr\" ","response":"range_response_count:1 size:5898"} {"level":"info","ts":"2026-04-08T01:45:05.753499Z","caller":"traceutil/trace.go:171","msg":"trace[1306973965] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-v86dr; range_end:; response_count:1; response_revision:12419; }","duration":"131.470318ms","start":"2026-04-08T01:45:05.622005Z","end":"2026-04-08T01:45:05.753475Z","steps":["trace[1306973965] 'agreement among raft nodes before linearized reading' (duration: 131.228132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:45:05.753483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.910633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T01:45:05.753569Z","caller":"traceutil/trace.go:171","msg":"trace[2011021623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12419; }","duration":"103.037185ms","start":"2026-04-08T01:45:05.650513Z","end":"2026-04-08T01:45:05.75355Z","steps":["trace[2011021623] 'agreement among raft nodes before linearized reading' (duration: 102.760528ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:45:07.953627Z","caller":"traceutil/trace.go:171","msg":"trace[1564603730] linearizableReadLoop","detail":"{readStateIndex:12881; appliedIndex:12880; }","duration":"101.389363ms","start":"2026-04-08T01:45:07.852214Z","end":"2026-04-08T01:45:07.953603Z","steps":["trace[1564603730] 'read index received' (duration: 100.011586ms)","trace[1564603730] 'applied index is now lower than readState.Index' (duration: 1.376626ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:45:07.953804Z","caller":"traceutil/trace.go:171","msg":"trace[722689031] transaction","detail":"{read_only:false; response_revision:12429; number_of_response:1; }","duration":"111.241513ms","start":"2026-04-08T01:45:07.842493Z","end":"2026-04-08T01:45:07.953735Z","steps":["trace[722689031] 'process raft request' (duration: 109.720433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:45:07.954393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.42675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T01:45:07.954503Z","caller":"traceutil/trace.go:171","msg":"trace[514938720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12430; }","duration":"110.602556ms","start":"2026-04-08T01:45:07.843878Z","end":"2026-04-08T01:45:07.954481Z","steps":["trace[514938720] 'agreement among raft nodes before linearized reading' (duration: 109.874956ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:45:09.774444Z","caller":"traceutil/trace.go:171","msg":"trace[29866694] linearizableReadLoop","detail":"{readStateIndex:12889; appliedIndex:12888; }","duration":"102.142933ms","start":"2026-04-08T01:45:09.672278Z","end":"2026-04-08T01:45:09.774421Z","steps":["trace[29866694] 'read index received' (duration: 101.863395ms)","trace[29866694] 'applied index is now lower than readState.Index' (duration: 278.548µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:45:09.774467Z","caller":"traceutil/trace.go:171","msg":"trace[1887536089] transaction","detail":"{read_only:false; response_revision:12437; number_of_response:1; }","duration":"120.940809ms","start":"2026-04-08T01:45:09.653496Z","end":"2026-04-08T01:45:09.774437Z","steps":["trace[1887536089] 'process raft request' (duration: 120.678761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:45:09.774656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.359978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/glance-glance-ks-service\" ","response":"range_response_count:1 size:748"} {"level":"info","ts":"2026-04-08T01:45:09.774724Z","caller":"traceutil/trace.go:171","msg":"trace[1133962654] range","detail":"{range_begin:/registry/rolebindings/openstack/glance-glance-ks-service; range_end:; response_count:1; response_revision:12437; }","duration":"102.47795ms","start":"2026-04-08T01:45:09.672226Z","end":"2026-04-08T01:45:09.774704Z","steps":["trace[1133962654] 'agreement among raft nodes before linearized reading' (duration: 102.292226ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:45:10.224245Z","caller":"traceutil/trace.go:171","msg":"trace[831153077] linearizableReadLoop","detail":"{readStateIndex:12894; appliedIndex:12893; }","duration":"216.885667ms","start":"2026-04-08T01:45:10.00733Z","end":"2026-04-08T01:45:10.224216Z","steps":["trace[831153077] 'read index received' (duration: 216.741133ms)","trace[831153077] 'applied index is now lower than readState.Index' (duration: 143.054µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:45:10.224302Z","caller":"traceutil/trace.go:171","msg":"trace[1246397913] transaction","detail":"{read_only:false; response_revision:12442; number_of_response:1; }","duration":"217.513403ms","start":"2026-04-08T01:45:10.006746Z","end":"2026-04-08T01:45:10.224259Z","steps":["trace[1246397913] 'process raft request' (duration: 217.272586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:45:10.224581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.203815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/glance-glance-ks-user\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-04-08T01:45:10.224707Z","caller":"traceutil/trace.go:171","msg":"trace[492723782] range","detail":"{range_begin:/registry/rolebindings/openstack/glance-glance-ks-user; range_end:; response_count:1; response_revision:12442; }","duration":"217.366141ms","start":"2026-04-08T01:45:10.007312Z","end":"2026-04-08T01:45:10.224678Z","steps":["trace[492723782] 'agreement among raft nodes before linearized reading' (duration: 217.017681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:45:10.225422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.073266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/glance-glance-metadefs-load\" ","response":"range_response_count:1 size:760"} {"level":"info","ts":"2026-04-08T01:45:10.225487Z","caller":"traceutil/trace.go:171","msg":"trace[756000106] transaction","detail":"{read_only:false; response_revision:12444; number_of_response:1; }","duration":"212.408998ms","start":"2026-04-08T01:45:10.013059Z","end":"2026-04-08T01:45:10.225468Z","steps":["trace[756000106] 'process raft request' (duration: 212.09352ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:45:10.225523Z","caller":"traceutil/trace.go:171","msg":"trace[16531384] range","detail":"{range_begin:/registry/rolebindings/openstack/glance-glance-metadefs-load; range_end:; response_count:1; response_revision:12445; }","duration":"196.179789ms","start":"2026-04-08T01:45:10.029291Z","end":"2026-04-08T01:45:10.22547Z","steps":["trace[16531384] 'agreement among raft nodes before linearized reading' (duration: 195.968384ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:45:10.225765Z","caller":"traceutil/trace.go:171","msg":"trace[509468561] transaction","detail":"{read_only:false; response_revision:12445; number_of_response:1; }","duration":"212.646695ms","start":"2026-04-08T01:45:10.013106Z","end":"2026-04-08T01:45:10.225753Z","steps":["trace[509468561] 'process raft request' (duration: 212.09734ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:45:10.225908Z","caller":"traceutil/trace.go:171","msg":"trace[440739205] transaction","detail":"{read_only:false; response_revision:12443; number_of_response:1; }","duration":"218.467959ms","start":"2026-04-08T01:45:10.007422Z","end":"2026-04-08T01:45:10.22589Z","steps":["trace[440739205] 'process raft request' (duration: 217.619437ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:45:26.275718Z","caller":"traceutil/trace.go:171","msg":"trace[1750865034] linearizableReadLoop","detail":"{readStateIndex:12977; appliedIndex:12976; }","duration":"151.818585ms","start":"2026-04-08T01:45:26.123872Z","end":"2026-04-08T01:45:26.27569Z","steps":["trace[1750865034] 'read index received' (duration: 102.369047ms)","trace[1750865034] 'applied index is now lower than readState.Index' (duration: 49.447948ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:45:26.276209Z","caller":"traceutil/trace.go:171","msg":"trace[1002068073] transaction","detail":"{read_only:false; response_revision:12522; number_of_response:1; }","duration":"199.575818ms","start":"2026-04-08T01:45:26.076557Z","end":"2026-04-08T01:45:26.276133Z","steps":["trace[1002068073] 'process raft request' (duration: 149.675439ms)","trace[1002068073] 'compare' (duration: 49.005136ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:45:26.276229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.325988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-config\" ","response":"range_response_count:1 size:598"} {"level":"info","ts":"2026-04-08T01:45:26.276456Z","caller":"traceutil/trace.go:171","msg":"trace[134911460] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-config; range_end:; response_count:1; response_revision:12522; }","duration":"152.605417ms","start":"2026-04-08T01:45:26.123823Z","end":"2026-04-08T01:45:26.276428Z","steps":["trace[134911460] 'agreement among raft nodes before linearized reading' (duration: 152.070352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:45:26.276445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.142083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-08T01:45:26.276551Z","caller":"traceutil/trace.go:171","msg":"trace[449796113] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12522; }","duration":"130.328297ms","start":"2026-04-08T01:45:26.146193Z","end":"2026-04-08T01:45:26.276522Z","steps":["trace[449796113] 'agreement among raft nodes before linearized reading' (duration: 129.962328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T01:45:26.278961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.080825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:390393"} {"level":"info","ts":"2026-04-08T01:45:26.279012Z","caller":"traceutil/trace.go:171","msg":"trace[1994085528] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:12522; }","duration":"154.149647ms","start":"2026-04-08T01:45:26.12485Z","end":"2026-04-08T01:45:26.279Z","steps":["trace[1994085528] 'agreement among raft nodes before linearized reading' (duration: 151.404765ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T01:45:26.434736Z","caller":"traceutil/trace.go:171","msg":"trace[1495930838] linearizableReadLoop","detail":"{readStateIndex:12980; appliedIndex:12978; }","duration":"104.520545ms","start":"2026-04-08T01:45:26.330182Z","end":"2026-04-08T01:45:26.434703Z","steps":["trace[1495930838] 'read index received' (duration: 43.117491ms)","trace[1495930838] 'applied index is now lower than readState.Index' (duration: 61.401904ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T01:45:26.434812Z","caller":"traceutil/trace.go:171","msg":"trace[734199334] transaction","detail":"{read_only:false; response_revision:12525; number_of_response:1; }","duration":"109.538028ms","start":"2026-04-08T01:45:26.325227Z","end":"2026-04-08T01:45:26.434765Z","steps":["trace[734199334] 'process raft request' (duration: 92.060385ms)","trace[734199334] 'compare' (duration: 17.256537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T01:45:26.435033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.828754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T01:45:26.435092Z","caller":"traceutil/trace.go:171","msg":"trace[1202829726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12525; }","duration":"104.945416ms","start":"2026-04-08T01:45:26.330133Z","end":"2026-04-08T01:45:26.435079Z","steps":["trace[1202829726] 'agreement among raft nodes before linearized reading' (duration: 104.748382ms)"],"step_count":1}