{"level":"warn","ts":"2026-04-14T06:19:28.607831Z","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-14T06:19:28.607953Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-14T06:19:28.608027Z","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-14T06:19:28.608171Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-04-14T06:19:28.60824Z","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-14T06:19:28.608946Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-04-14T06:19:28.609025Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-14T06:19:28.614674Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.347984ms"} {"level":"info","ts":"2026-04-14T06:19:28.621299Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-04-14T06:19:28.6214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T06:19:28.621423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-04-14T06:19:28.621439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T06:19:28.621447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-04-14T06:19:28.621486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-04-14T06:19:28.626829Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T06:19:28.629196Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T06:19:28.631867Z","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-14T06:19:28.635318Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T06:19:28.635674Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T06:19:28.636871Z","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-14T06:19:28.636995Z","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-14T06:19:28.637007Z","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-14T06:19:28.636997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-04-14T06:19:28.638467Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-04-14T06:19:28.641897Z","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-14T06:19:28.641982Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-04-14T06:19:28.642035Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-04-14T06:19:28.642239Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T06:19:28.6423Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T06:19:28.92287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T06:19:28.922975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T06:19:28.923017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-04-14T06:19:28.923039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-04-14T06:19:28.923051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-04-14T06:19:28.923066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-04-14T06:19:28.923084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-04-14T06:19:28.924051Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T06:19:28.924098Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T06:19:28.924191Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T06:19:28.92423Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:19:28.924375Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T06:19:28.924443Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T06:19:28.925189Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:19:28.925289Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:19:28.925321Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:19:28.92657Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-04-14T06:19:28.926944Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T06:19:59.158434Z","caller":"traceutil/trace.go:171","msg":"trace[1018095667] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"173.170123ms","start":"2026-04-14T06:19:58.98524Z","end":"2026-04-14T06:19:59.15841Z","steps":["trace[1018095667] 'process raft request' (duration: 173.06004ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.165656Z","caller":"traceutil/trace.go:171","msg":"trace[755210279] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"177.774296ms","start":"2026-04-14T06:19:58.987845Z","end":"2026-04-14T06:19:59.165619Z","steps":["trace[755210279] 'process raft request' (duration: 177.467477ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.165913Z","caller":"traceutil/trace.go:171","msg":"trace[355904111] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"177.814188ms","start":"2026-04-14T06:19:58.988077Z","end":"2026-04-14T06:19:59.165891Z","steps":["trace[355904111] 'process raft request' (duration: 177.366264ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.166026Z","caller":"traceutil/trace.go:171","msg":"trace[1986478090] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"165.732569ms","start":"2026-04-14T06:19:59.000271Z","end":"2026-04-14T06:19:59.166004Z","steps":["trace[1986478090] 'process raft request' (duration: 165.488722ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.166206Z","caller":"traceutil/trace.go:171","msg":"trace[903088049] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"158.911012ms","start":"2026-04-14T06:19:59.007286Z","end":"2026-04-14T06:19:59.166197Z","steps":["trace[903088049] 'process raft request' (duration: 158.515401ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.166387Z","caller":"traceutil/trace.go:171","msg":"trace[448060931] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"159.008864ms","start":"2026-04-14T06:19:59.007371Z","end":"2026-04-14T06:19:59.16638Z","steps":["trace[448060931] 'process raft request' (duration: 158.769677ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.166477Z","caller":"traceutil/trace.go:171","msg":"trace[120662837] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"158.990703ms","start":"2026-04-14T06:19:59.00747Z","end":"2026-04-14T06:19:59.16646Z","steps":["trace[120662837] 'process raft request' (duration: 158.801888ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.166848Z","caller":"traceutil/trace.go:171","msg":"trace[76088752] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"154.00841ms","start":"2026-04-14T06:19:59.012817Z","end":"2026-04-14T06:19:59.166826Z","steps":["trace[76088752] 'process raft request' (duration: 153.546917ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.167047Z","caller":"traceutil/trace.go:171","msg":"trace[1390027490] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"147.41738ms","start":"2026-04-14T06:19:59.019609Z","end":"2026-04-14T06:19:59.167027Z","steps":["trace[1390027490] 'process raft request' (duration: 147.118431ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.167149Z","caller":"traceutil/trace.go:171","msg":"trace[1264602260] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"144.061912ms","start":"2026-04-14T06:19:59.023071Z","end":"2026-04-14T06:19:59.167133Z","steps":["trace[1264602260] 'process raft request' (duration: 143.826835ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:59.167277Z","caller":"traceutil/trace.go:171","msg":"trace[574714501] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"124.288459ms","start":"2026-04-14T06:19:59.04298Z","end":"2026-04-14T06:19:59.167269Z","steps":["trace[574714501] 'process raft request' (duration: 124.021881ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:05.564102Z","caller":"traceutil/trace.go:171","msg":"trace[1015469342] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"171.467807ms","start":"2026-04-14T06:20:05.392601Z","end":"2026-04-14T06:20:05.564069Z","steps":["trace[1015469342] 'process raft request' (duration: 167.577224ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:05.892182Z","caller":"traceutil/trace.go:171","msg":"trace[1906440091] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"134.765263ms","start":"2026-04-14T06:20:05.757388Z","end":"2026-04-14T06:20:05.892153Z","steps":["trace[1906440091] 'read index received' (duration: 134.619179ms)","trace[1906440091] 'applied index is now lower than readState.Index' (duration: 145.204µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:05.892264Z","caller":"traceutil/trace.go:171","msg":"trace[596687200] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"268.113714ms","start":"2026-04-14T06:20:05.624087Z","end":"2026-04-14T06:20:05.892201Z","steps":["trace[596687200] 'process raft request' (duration: 267.942649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:05.892398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.987539ms","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-14T06:20:05.892487Z","caller":"traceutil/trace.go:171","msg":"trace[1576840962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"135.122233ms","start":"2026-04-14T06:20:05.757346Z","end":"2026-04-14T06:20:05.892468Z","steps":["trace[1576840962] 'agreement among raft nodes before linearized reading' (duration: 134.904857ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:06.077639Z","caller":"traceutil/trace.go:171","msg":"trace[317782302] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"165.231495ms","start":"2026-04-14T06:20:05.912384Z","end":"2026-04-14T06:20:06.077616Z","steps":["trace[317782302] 'process raft request' (duration: 165.0626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:20.709888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.510907ms","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-14T06:20:20.71002Z","caller":"traceutil/trace.go:171","msg":"trace[811722939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:738; }","duration":"123.680802ms","start":"2026-04-14T06:20:20.586311Z","end":"2026-04-14T06:20:20.709991Z","steps":["trace[811722939] 'range keys from in-memory index tree' (duration: 123.27712ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:20.850984Z","caller":"traceutil/trace.go:171","msg":"trace[44917301] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"136.04724ms","start":"2026-04-14T06:20:20.714914Z","end":"2026-04-14T06:20:20.850961Z","steps":["trace[44917301] 'process raft request' (duration: 135.890495ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:47.56859Z","caller":"traceutil/trace.go:171","msg":"trace[1125829070] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"195.290476ms","start":"2026-04-14T06:20:47.373265Z","end":"2026-04-14T06:20:47.568556Z","steps":["trace[1125829070] 'process raft request' (duration: 195.057529ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:34.13301Z","caller":"traceutil/trace.go:171","msg":"trace[1318167791] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"269.272343ms","start":"2026-04-14T06:21:33.86369Z","end":"2026-04-14T06:21:34.132962Z","steps":["trace[1318167791] 'process raft request' (duration: 268.969354ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:34.59055Z","caller":"traceutil/trace.go:171","msg":"trace[988371978] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"229.183462ms","start":"2026-04-14T06:21:34.361324Z","end":"2026-04-14T06:21:34.590507Z","steps":["trace[988371978] 'process raft request' (duration: 218.378569ms)","trace[988371978] 'compare' (duration: 10.618298ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:21:34.590816Z","caller":"traceutil/trace.go:171","msg":"trace[1964944972] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"151.092803ms","start":"2026-04-14T06:21:34.439709Z","end":"2026-04-14T06:21:34.590802Z","steps":["trace[1964944972] 'process raft request' (duration: 150.751943ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:34.590786Z","caller":"traceutil/trace.go:171","msg":"trace[1157251922] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1876; }","duration":"122.892827ms","start":"2026-04-14T06:21:34.467846Z","end":"2026-04-14T06:21:34.590739Z","steps":["trace[1157251922] 'read index received' (duration: 111.760125ms)","trace[1157251922] 'applied index is now lower than readState.Index' (duration: 11.130582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:21:34.590972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.119293ms","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-04-14T06:21:34.591038Z","caller":"traceutil/trace.go:171","msg":"trace[1384036300] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1810; }","duration":"123.193465ms","start":"2026-04-14T06:21:34.467829Z","end":"2026-04-14T06:21:34.591023Z","steps":["trace[1384036300] 'agreement among raft nodes before linearized reading' (duration: 123.02876ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:46.737884Z","caller":"traceutil/trace.go:171","msg":"trace[1701780324] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"104.268737ms","start":"2026-04-14T06:21:46.633592Z","end":"2026-04-14T06:21:46.737861Z","steps":["trace[1701780324] 'process raft request' (duration: 103.976309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:22:13.860391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.046329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:22:13.860472Z","caller":"traceutil/trace.go:171","msg":"trace[1657709599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2076; }","duration":"125.178803ms","start":"2026-04-14T06:22:13.735277Z","end":"2026-04-14T06:22:13.860456Z","steps":["trace[1657709599] 'range keys from in-memory index tree' (duration: 124.943537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:22:25.127303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.550121ms","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-04-14T06:22:25.127384Z","caller":"traceutil/trace.go:171","msg":"trace[1492730670] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2134; }","duration":"118.694685ms","start":"2026-04-14T06:22:25.008671Z","end":"2026-04-14T06:22:25.127366Z","steps":["trace[1492730670] 'range keys from in-memory index tree' (duration: 118.325813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:22:25.64657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.593312ms","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-14T06:22:25.648167Z","caller":"traceutil/trace.go:171","msg":"trace[1457023323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2135; }","duration":"148.311142ms","start":"2026-04-14T06:22:25.499788Z","end":"2026-04-14T06:22:25.648099Z","steps":["trace[1457023323] 'range keys from in-memory index tree' (duration: 146.276943ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:22:25.864516Z","caller":"traceutil/trace.go:171","msg":"trace[510756040] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"210.848231ms","start":"2026-04-14T06:22:25.653644Z","end":"2026-04-14T06:22:25.864493Z","steps":["trace[510756040] 'process raft request' (duration: 210.678416ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:22:25.864674Z","caller":"traceutil/trace.go:171","msg":"trace[1160597588] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"209.401801ms","start":"2026-04-14T06:22:25.655238Z","end":"2026-04-14T06:22:25.86464Z","steps":["trace[1160597588] 'process raft request' (duration: 209.202345ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:22:26.011746Z","caller":"traceutil/trace.go:171","msg":"trace[1518722124] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"142.804123ms","start":"2026-04-14T06:22:25.868872Z","end":"2026-04-14T06:22:26.011676Z","steps":["trace[1518722124] 'process raft request' (duration: 137.861859ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:23:17.566119Z","caller":"traceutil/trace.go:171","msg":"trace[1405114781] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"157.379122ms","start":"2026-04-14T06:23:17.408693Z","end":"2026-04-14T06:23:17.566072Z","steps":["trace[1405114781] 'read index received' (duration: 135.606453ms)","trace[1405114781] 'applied index is now lower than readState.Index' (duration: 21.766209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:23:17.566448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.717121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-14T06:23:17.566558Z","caller":"traceutil/trace.go:171","msg":"trace[1924853456] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2588; }","duration":"157.903086ms","start":"2026-04-14T06:23:17.408629Z","end":"2026-04-14T06:23:17.566533Z","steps":["trace[1924853456] 'agreement among raft nodes before linearized reading' (duration: 157.572537ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:23:50.524558Z","caller":"traceutil/trace.go:171","msg":"trace[2003851652] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"188.079849ms","start":"2026-04-14T06:23:50.336438Z","end":"2026-04-14T06:23:50.524517Z","steps":["trace[2003851652] 'process raft request' (duration: 111.327479ms)","trace[2003851652] 'compare' (duration: 76.479062ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:23:51.005572Z","caller":"traceutil/trace.go:171","msg":"trace[471575731] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"139.931676ms","start":"2026-04-14T06:23:50.865617Z","end":"2026-04-14T06:23:51.005549Z","steps":["trace[471575731] 'process raft request' (duration: 84.745281ms)","trace[471575731] 'compare' (duration: 55.081142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:26:21.49619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.879897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:26:21.496288Z","caller":"traceutil/trace.go:171","msg":"trace[1337805493] linearizableReadLoop","detail":"{readStateIndex:3926; appliedIndex:3925; }","duration":"195.118607ms","start":"2026-04-14T06:26:21.301157Z","end":"2026-04-14T06:26:21.496275Z","steps":["trace[1337805493] 'read index received' (duration: 77.611982ms)","trace[1337805493] 'applied index is now lower than readState.Index' (duration: 117.505095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:26:21.49634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.190619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:26:21.496328Z","caller":"traceutil/trace.go:171","msg":"trace[1790910422] transaction","detail":"{read_only:false; response_revision:3785; number_of_response:1; }","duration":"238.547492ms","start":"2026-04-14T06:26:21.257748Z","end":"2026-04-14T06:26:21.496296Z","steps":["trace[1790910422] 'process raft request' (duration: 121.082958ms)","trace[1790910422] 'compare' (duration: 116.673841ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:26:21.496364Z","caller":"traceutil/trace.go:171","msg":"trace[83338832] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3785; }","duration":"195.21381ms","start":"2026-04-14T06:26:21.301143Z","end":"2026-04-14T06:26:21.496357Z","steps":["trace[83338832] 'agreement among raft nodes before linearized reading' (duration: 195.166928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:49.820324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.177558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf8f0f33-cf39-4e72-8c54-87225896b75e\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-14T06:27:49.820407Z","caller":"traceutil/trace.go:171","msg":"trace[75787492] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf8f0f33-cf39-4e72-8c54-87225896b75e; range_end:; response_count:1; response_revision:4985; }","duration":"115.316312ms","start":"2026-04-14T06:27:49.705073Z","end":"2026-04-14T06:27:49.820389Z","steps":["trace[75787492] 'range keys from in-memory index tree' (duration: 114.947821ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:00.678388Z","caller":"traceutil/trace.go:171","msg":"trace[588852840] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"100.198577ms","start":"2026-04-14T06:28:00.578154Z","end":"2026-04-14T06:28:00.678352Z","steps":["trace[588852840] 'process raft request' (duration: 54.539872ms)","trace[588852840] 'compare' (duration: 45.418568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:28:09.043929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.168987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:28:09.044045Z","caller":"traceutil/trace.go:171","msg":"trace[1388369133] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5527; }","duration":"170.468031ms","start":"2026-04-14T06:28:08.87356Z","end":"2026-04-14T06:28:09.044028Z","steps":["trace[1388369133] 'read index received' (duration: 21.082818ms)","trace[1388369133] 'applied index is now lower than readState.Index' (duration: 149.384103ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:28:09.044099Z","caller":"traceutil/trace.go:171","msg":"trace[2104846208] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"265.9033ms","start":"2026-04-14T06:28:08.778144Z","end":"2026-04-14T06:28:09.044047Z","steps":["trace[2104846208] 'process raft request' (duration: 116.528138ms)","trace[2104846208] 'compare' (duration: 149.061633ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:28:09.044378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.81243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6a5d75f2-df77-4310-9651-0114cdde4ee1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-14T06:28:09.044428Z","caller":"traceutil/trace.go:171","msg":"trace[435583941] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6a5d75f2-df77-4310-9651-0114cdde4ee1; range_end:; response_count:1; response_revision:5332; }","duration":"170.865792ms","start":"2026-04-14T06:28:08.87355Z","end":"2026-04-14T06:28:09.044416Z","steps":["trace[435583941] 'agreement among raft nodes before linearized reading' (duration: 170.526252ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:09.508239Z","caller":"traceutil/trace.go:171","msg":"trace[231549019] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"121.007326ms","start":"2026-04-14T06:28:09.387203Z","end":"2026-04-14T06:28:09.50821Z","steps":["trace[231549019] 'process raft request' (duration: 120.716478ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:09.659615Z","caller":"traceutil/trace.go:171","msg":"trace[620475701] linearizableReadLoop","detail":"{readStateIndex:5530; appliedIndex:5529; }","duration":"117.503555ms","start":"2026-04-14T06:28:09.542088Z","end":"2026-04-14T06:28:09.659592Z","steps":["trace[620475701] 'read index received' (duration: 43.111542ms)","trace[620475701] 'applied index is now lower than readState.Index' (duration: 74.390993ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:28:09.659672Z","caller":"traceutil/trace.go:171","msg":"trace[1243321587] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"146.85251ms","start":"2026-04-14T06:28:09.512806Z","end":"2026-04-14T06:28:09.659658Z","steps":["trace[1243321587] 'process raft request' (duration: 72.420345ms)","trace[1243321587] 'compare' (duration: 74.277741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:28:09.659774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.677351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:28:09.659805Z","caller":"traceutil/trace.go:171","msg":"trace[439851221] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:5334; }","duration":"117.759903ms","start":"2026-04-14T06:28:09.542037Z","end":"2026-04-14T06:28:09.659797Z","steps":["trace[439851221] 'agreement among raft nodes before linearized reading' (duration: 117.685411ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:28.959345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-04-14T06:29:29.028182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"67.082252ms","hash":3311071907,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":33935360,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-14T06:29:29.028308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3311071907,"revision":3000,"compact-revision":-1} {"level":"warn","ts":"2026-04-14T06:34:27.886581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.518897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:34:27.886572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.450242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:34:27.886709Z","caller":"traceutil/trace.go:171","msg":"trace[2130907658] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:8269; }","duration":"394.705662ms","start":"2026-04-14T06:34:27.491981Z","end":"2026-04-14T06:34:27.886687Z","steps":["trace[2130907658] 'count revisions from in-memory index tree' (duration: 394.435654ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:27.886744Z","caller":"traceutil/trace.go:171","msg":"trace[1583285447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8269; }","duration":"314.678808ms","start":"2026-04-14T06:34:27.572035Z","end":"2026-04-14T06:34:27.886714Z","steps":["trace[1583285447] 'range keys from in-memory index tree' (duration: 314.265667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:34:27.886758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:34:27.491962Z","time spent":"394.783964ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:34:27.886816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:34:27.572013Z","time spent":"314.788441ms","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:34:27.886916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.338207ms","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-14T06:34:27.887012Z","caller":"traceutil/trace.go:171","msg":"trace[158155432] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:8269; }","duration":"439.499552ms","start":"2026-04-14T06:34:27.447493Z","end":"2026-04-14T06:34:27.886993Z","steps":["trace[158155432] 'count revisions from in-memory index tree' (duration: 439.229134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:34:27.887055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:34:27.447466Z","time spent":"439.577933ms","remote":"127.0.0.1:38882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-04-14T06:34:27.887143Z","caller":"traceutil/trace.go:171","msg":"trace[1152192146] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"287.032231ms","start":"2026-04-14T06:34:27.600088Z","end":"2026-04-14T06:34:27.88712Z","steps":["trace[1152192146] 'process raft request' (duration: 274.012817ms)","trace[1152192146] 'compare' (duration: 12.766167ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:34:27.887096Z","caller":"traceutil/trace.go:171","msg":"trace[1590865984] linearizableReadLoop","detail":"{readStateIndex:8557; appliedIndex:8556; }","duration":"146.168318ms","start":"2026-04-14T06:34:27.740882Z","end":"2026-04-14T06:34:27.88705Z","steps":["trace[1590865984] 'read index received' (duration: 133.250996ms)","trace[1590865984] 'applied index is now lower than readState.Index' (duration: 12.915791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:34:27.887326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.434385ms","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-04-14T06:34:27.887369Z","caller":"traceutil/trace.go:171","msg":"trace[29976387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8270; }","duration":"146.527567ms","start":"2026-04-14T06:34:27.740832Z","end":"2026-04-14T06:34:27.887359Z","steps":["trace[29976387] 'agreement among raft nodes before linearized reading' (duration: 146.349942ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:28.964874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6107} {"level":"info","ts":"2026-04-14T06:34:29.069172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6107,"took":"102.287414ms","hash":1153307830,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":23162880,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-14T06:34:29.069253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1153307830,"revision":6107,"compact-revision":3000} {"level":"warn","ts":"2026-04-14T06:34:40.224437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.116365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:34:40.224554Z","caller":"traceutil/trace.go:171","msg":"trace[1196798820] linearizableReadLoop","detail":"{readStateIndex:8624; appliedIndex:8623; }","duration":"177.957802ms","start":"2026-04-14T06:34:40.046577Z","end":"2026-04-14T06:34:40.224535Z","steps":["trace[1196798820] 'read index received' (duration: 14.670972ms)","trace[1196798820] 'applied index is now lower than readState.Index' (duration: 163.28544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:34:40.224694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.117177ms","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-14T06:34:40.224715Z","caller":"traceutil/trace.go:171","msg":"trace[847346206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8334; }","duration":"178.182028ms","start":"2026-04-14T06:34:40.046527Z","end":"2026-04-14T06:34:40.224709Z","steps":["trace[847346206] 'agreement among raft nodes before linearized reading' (duration: 178.060535ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:40.224828Z","caller":"traceutil/trace.go:171","msg":"trace[17905293] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"204.156297ms","start":"2026-04-14T06:34:40.020637Z","end":"2026-04-14T06:34:40.224793Z","steps":["trace[17905293] 'process raft request' (duration: 40.614959ms)","trace[17905293] 'compare' (duration: 162.985032ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:34:40.471249Z","caller":"traceutil/trace.go:171","msg":"trace[940711225] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"242.353046ms","start":"2026-04-14T06:34:40.228866Z","end":"2026-04-14T06:34:40.471219Z","steps":["trace[940711225] 'process raft request' (duration: 180.64748ms)","trace[940711225] 'compare' (duration: 61.406028ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:34:40.471263Z","caller":"traceutil/trace.go:171","msg":"trace[1324823446] linearizableReadLoop","detail":"{readStateIndex:8625; appliedIndex:8624; }","duration":"231.705319ms","start":"2026-04-14T06:34:40.239501Z","end":"2026-04-14T06:34:40.471207Z","steps":["trace[1324823446] 'read index received' (duration: 170.062974ms)","trace[1324823446] 'applied index is now lower than readState.Index' (duration: 61.640415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:34:40.471503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.965797ms","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-04-14T06:34:40.471539Z","caller":"traceutil/trace.go:171","msg":"trace[516136188] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8335; }","duration":"232.08009ms","start":"2026-04-14T06:34:40.239447Z","end":"2026-04-14T06:34:40.471527Z","steps":["trace[516136188] 'agreement among raft nodes before linearized reading' (duration: 231.967787ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:41.552525Z","caller":"traceutil/trace.go:171","msg":"trace[915380570] transaction","detail":"{read_only:false; response_revision:8342; number_of_response:1; }","duration":"103.336775ms","start":"2026-04-14T06:34:41.449161Z","end":"2026-04-14T06:34:41.552498Z","steps":["trace[915380570] 'process raft request' (duration: 103.066697ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:35:06.212292Z","caller":"traceutil/trace.go:171","msg":"trace[654078987] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"100.225095ms","start":"2026-04-14T06:35:06.112018Z","end":"2026-04-14T06:35:06.212243Z","steps":["trace[654078987] 'process raft request' (duration: 98.212187ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:35:09.798466Z","caller":"traceutil/trace.go:171","msg":"trace[260995380] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"145.517379ms","start":"2026-04-14T06:35:09.652921Z","end":"2026-04-14T06:35:09.798439Z","steps":["trace[260995380] 'process raft request' (duration: 145.362514ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:35:13.320732Z","caller":"traceutil/trace.go:171","msg":"trace[1353369995] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"138.769914ms","start":"2026-04-14T06:35:13.181914Z","end":"2026-04-14T06:35:13.320684Z","steps":["trace[1353369995] 'process raft request' (duration: 115.216136ms)","trace[1353369995] 'compare' (duration: 23.386803ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:35:23.387137Z","caller":"traceutil/trace.go:171","msg":"trace[1482941365] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"134.887153ms","start":"2026-04-14T06:35:23.25222Z","end":"2026-04-14T06:35:23.387107Z","steps":["trace[1482941365] 'process raft request' (duration: 102.384807ms)","trace[1482941365] 'compare' (duration: 32.388012ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:35:23.387241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.314444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-txzmz\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-04-14T06:35:23.387383Z","caller":"traceutil/trace.go:171","msg":"trace[110333491] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-txzmz; range_end:; response_count:1; response_revision:8661; }","duration":"135.514741ms","start":"2026-04-14T06:35:23.251843Z","end":"2026-04-14T06:35:23.387357Z","steps":["trace[110333491] 'range keys from in-memory index tree' (duration: 135.074658ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:35:23.622505Z","caller":"traceutil/trace.go:171","msg":"trace[4273674] linearizableReadLoop","detail":"{readStateIndex:8969; appliedIndex:8968; }","duration":"224.815451ms","start":"2026-04-14T06:35:23.397656Z","end":"2026-04-14T06:35:23.622471Z","steps":["trace[4273674] 'read index received' (duration: 198.411371ms)","trace[4273674] 'applied index is now lower than readState.Index' (duration: 26.40212ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:35:23.62266Z","caller":"traceutil/trace.go:171","msg":"trace[1017982781] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"226.743328ms","start":"2026-04-14T06:35:23.395824Z","end":"2026-04-14T06:35:23.622568Z","steps":["trace[1017982781] 'process raft request' (duration: 200.188593ms)","trace[1017982781] 'compare' (duration: 26.281007ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:35:23.622742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.041448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:35:23.623258Z","caller":"traceutil/trace.go:171","msg":"trace[406584947] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8664; }","duration":"225.639385ms","start":"2026-04-14T06:35:23.397601Z","end":"2026-04-14T06:35:23.62324Z","steps":["trace[406584947] 'agreement among raft nodes before linearized reading' (duration: 225.025308ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:35:24.66472Z","caller":"traceutil/trace.go:171","msg":"trace[995112368] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"213.33118ms","start":"2026-04-14T06:35:24.451359Z","end":"2026-04-14T06:35:24.66469Z","steps":["trace[995112368] 'process raft request' (duration: 203.083125ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:35:24.754195Z","caller":"traceutil/trace.go:171","msg":"trace[1002831364] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"299.323275ms","start":"2026-04-14T06:35:24.454836Z","end":"2026-04-14T06:35:24.754159Z","steps":["trace[1002831364] 'process raft request' (duration: 299.169791ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:35:24.754371Z","caller":"traceutil/trace.go:171","msg":"trace[1103122005] linearizableReadLoop","detail":"{readStateIndex:8977; appliedIndex:8975; }","duration":"184.552122ms","start":"2026-04-14T06:35:24.56981Z","end":"2026-04-14T06:35:24.754362Z","steps":["trace[1103122005] 'read index received' (duration: 84.652677ms)","trace[1103122005] 'applied index is now lower than readState.Index' (duration: 99.898585ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:35:24.75455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.731987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:35:24.75462Z","caller":"traceutil/trace.go:171","msg":"trace[1690968899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8672; }","duration":"184.83514ms","start":"2026-04-14T06:35:24.569742Z","end":"2026-04-14T06:35:24.754577Z","steps":["trace[1690968899] 'agreement among raft nodes before linearized reading' (duration: 184.680106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:35:24.754853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.584522ms","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-04-14T06:35:24.754941Z","caller":"traceutil/trace.go:171","msg":"trace[545530493] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8672; }","duration":"127.734727ms","start":"2026-04-14T06:35:24.627187Z","end":"2026-04-14T06:35:24.754922Z","steps":["trace[545530493] 'agreement among raft nodes before linearized reading' (duration: 127.589843ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:35:51.075818Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243a.wal"} {"level":"info","ts":"2026-04-14T06:37:14.49824Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T06:37:14.504276Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-14T06:37:14.504379Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-14T06:39:28.972809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8278} {"level":"info","ts":"2026-04-14T06:39:29.058443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8278,"took":"83.467343ms","hash":3362695746,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":24776704,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-14T06:39:29.058515Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3362695746,"revision":8278,"compact-revision":6107} {"level":"info","ts":"2026-04-14T06:41:55.681973Z","caller":"traceutil/trace.go:171","msg":"trace[641121774] linearizableReadLoop","detail":"{readStateIndex:12120; appliedIndex:12119; }","duration":"109.925745ms","start":"2026-04-14T06:41:55.572015Z","end":"2026-04-14T06:41:55.68194Z","steps":["trace[641121774] 'read index received' (duration: 109.628457ms)","trace[641121774] 'applied index is now lower than readState.Index' (duration: 296.018µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:41:55.682184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.148751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:41:55.682331Z","caller":"traceutil/trace.go:171","msg":"trace[1034016883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11720; }","duration":"110.343307ms","start":"2026-04-14T06:41:55.571965Z","end":"2026-04-14T06:41:55.682308Z","steps":["trace[1034016883] 'agreement among raft nodes before linearized reading' (duration: 110.12742ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:41:55.682128Z","caller":"traceutil/trace.go:171","msg":"trace[335111099] transaction","detail":"{read_only:false; response_revision:11720; number_of_response:1; }","duration":"114.444194ms","start":"2026-04-14T06:41:55.567634Z","end":"2026-04-14T06:41:55.682079Z","steps":["trace[335111099] 'process raft request' (duration: 114.094084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:42:19.451752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.480351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:42:19.451842Z","caller":"traceutil/trace.go:171","msg":"trace[343399495] linearizableReadLoop","detail":"{readStateIndex:12253; appliedIndex:12252; }","duration":"147.457125ms","start":"2026-04-14T06:42:19.304371Z","end":"2026-04-14T06:42:19.451828Z","steps":["trace[343399495] 'read index received' (duration: 56.292µs)","trace[343399495] 'applied index is now lower than readState.Index' (duration: 147.399773ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:42:19.451906Z","caller":"traceutil/trace.go:171","msg":"trace[1147548133] transaction","detail":"{read_only:false; response_revision:11848; number_of_response:1; }","duration":"301.50802ms","start":"2026-04-14T06:42:19.150382Z","end":"2026-04-14T06:42:19.45189Z","steps":["trace[1147548133] 'process raft request' (duration: 34.810532ms)","trace[1147548133] 'compare' (duration: 266.199383ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:42:19.451949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.589408ms","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-04-14T06:42:19.451966Z","caller":"traceutil/trace.go:171","msg":"trace[925405182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11848; }","duration":"147.650021ms","start":"2026-04-14T06:42:19.304309Z","end":"2026-04-14T06:42:19.451959Z","steps":["trace[925405182] 'agreement among raft nodes before linearized reading' (duration: 147.551758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:42:19.451977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:42:19.150366Z","time spent":"301.580861ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:44:28.978327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10502} {"level":"info","ts":"2026-04-14T06:44:29.056208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10502,"took":"74.784246ms","hash":2065148635,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":25919488,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-14T06:44:29.056313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2065148635,"revision":10502,"compact-revision":8278} {"level":"warn","ts":"2026-04-14T06:48:16.961451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.447512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:48:16.96158Z","caller":"traceutil/trace.go:171","msg":"trace[968169813] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:14280; }","duration":"119.116321ms","start":"2026-04-14T06:48:16.842426Z","end":"2026-04-14T06:48:16.961543Z","steps":["trace[968169813] 'count revisions from in-memory index tree' (duration: 118.319218ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:48:41.2222Z","caller":"traceutil/trace.go:171","msg":"trace[1508724837] transaction","detail":"{read_only:false; response_revision:14408; number_of_response:1; }","duration":"105.684364ms","start":"2026-04-14T06:48:41.11647Z","end":"2026-04-14T06:48:41.222155Z","steps":["trace[1508724837] 'process raft request' (duration: 105.415615ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:48:58.662177Z","caller":"traceutil/trace.go:171","msg":"trace[2007101079] transaction","detail":"{read_only:false; response_revision:14514; number_of_response:1; }","duration":"124.191727ms","start":"2026-04-14T06:48:58.537953Z","end":"2026-04-14T06:48:58.662144Z","steps":["trace[2007101079] 'process raft request' (duration: 122.126827ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:49:21.487808Z","caller":"traceutil/trace.go:171","msg":"trace[1371812575] transaction","detail":"{read_only:false; response_revision:14826; number_of_response:1; }","duration":"116.942148ms","start":"2026-04-14T06:49:21.370822Z","end":"2026-04-14T06:49:21.487765Z","steps":["trace[1371812575] 'process raft request' (duration: 116.575328ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:49:21.615535Z","caller":"traceutil/trace.go:171","msg":"trace[1787713900] transaction","detail":"{read_only:false; response_revision:14827; number_of_response:1; }","duration":"123.172448ms","start":"2026-04-14T06:49:21.49234Z","end":"2026-04-14T06:49:21.615512Z","steps":["trace[1787713900] 'process raft request' (duration: 123.014663ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:49:21.693847Z","caller":"traceutil/trace.go:171","msg":"trace[2039569235] linearizableReadLoop","detail":"{readStateIndex:15342; appliedIndex:15340; }","duration":"123.862819ms","start":"2026-04-14T06:49:21.569959Z","end":"2026-04-14T06:49:21.693822Z","steps":["trace[2039569235] 'read index received' (duration: 45.377292ms)","trace[2039569235] 'applied index is now lower than readState.Index' (duration: 78.484267ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:49:21.69393Z","caller":"traceutil/trace.go:171","msg":"trace[1352508477] transaction","detail":"{read_only:false; response_revision:14828; number_of_response:1; }","duration":"197.959149ms","start":"2026-04-14T06:49:21.495936Z","end":"2026-04-14T06:49:21.693895Z","steps":["trace[1352508477] 'process raft request' (duration: 197.749112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:49:21.694011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.044583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:49:21.694054Z","caller":"traceutil/trace.go:171","msg":"trace[1931013136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14828; }","duration":"124.143676ms","start":"2026-04-14T06:49:21.5699Z","end":"2026-04-14T06:49:21.694044Z","steps":["trace[1931013136] 'agreement among raft nodes before linearized reading' (duration: 124.055894ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:49:28.986413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12723} {"level":"info","ts":"2026-04-14T06:49:29.063353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12723,"took":"74.440879ms","hash":1475465491,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":28307456,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-14T06:49:29.063413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1475465491,"revision":12723,"compact-revision":10502} {"level":"info","ts":"2026-04-14T06:50:36.987909Z","caller":"traceutil/trace.go:171","msg":"trace[1917577389] transaction","detail":"{read_only:false; response_revision:15583; number_of_response:1; }","duration":"109.751351ms","start":"2026-04-14T06:50:36.878118Z","end":"2026-04-14T06:50:36.987869Z","steps":["trace[1917577389] 'process raft request' (duration: 109.534324ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:50:37.21127Z","caller":"traceutil/trace.go:171","msg":"trace[174800712] transaction","detail":"{read_only:false; response_revision:15584; number_of_response:1; }","duration":"157.530351ms","start":"2026-04-14T06:50:37.053694Z","end":"2026-04-14T06:50:37.211224Z","steps":["trace[174800712] 'process raft request' (duration: 59.043545ms)","trace[174800712] 'compare' (duration: 98.001561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:50:38.044186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.816798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-14T06:50:38.044291Z","caller":"traceutil/trace.go:171","msg":"trace[1590917301] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15588; }","duration":"104.939071ms","start":"2026-04-14T06:50:37.939325Z","end":"2026-04-14T06:50:38.044264Z","steps":["trace[1590917301] 'range keys from in-memory index tree' (duration: 104.605021ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:50:38.314824Z","caller":"traceutil/trace.go:171","msg":"trace[1476333430] linearizableReadLoop","detail":"{readStateIndex:16127; appliedIndex:16126; }","duration":"207.025881ms","start":"2026-04-14T06:50:38.107726Z","end":"2026-04-14T06:50:38.314752Z","steps":["trace[1476333430] 'read index received' (duration: 126.297268ms)","trace[1476333430] 'applied index is now lower than readState.Index' (duration: 80.727603ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:50:38.314941Z","caller":"traceutil/trace.go:171","msg":"trace[1438542533] transaction","detail":"{read_only:false; response_revision:15590; number_of_response:1; }","duration":"208.116703ms","start":"2026-04-14T06:50:38.10681Z","end":"2026-04-14T06:50:38.314927Z","steps":["trace[1438542533] 'process raft request' (duration: 127.195725ms)","trace[1438542533] 'compare' (duration: 80.227667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:50:38.315028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.275268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:50:38.316844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.008997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-14T06:50:38.316901Z","caller":"traceutil/trace.go:171","msg":"trace[1159133204] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15592; }","duration":"209.10478ms","start":"2026-04-14T06:50:38.107781Z","end":"2026-04-14T06:50:38.316885Z","steps":["trace[1159133204] 'agreement among raft nodes before linearized reading' (duration: 208.933095ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:50:38.316992Z","caller":"traceutil/trace.go:171","msg":"trace[421070007] transaction","detail":"{read_only:false; response_revision:15591; number_of_response:1; }","duration":"204.60172ms","start":"2026-04-14T06:50:38.112362Z","end":"2026-04-14T06:50:38.316964Z","steps":["trace[421070007] 'process raft request' (duration: 204.217959ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:50:38.317114Z","caller":"traceutil/trace.go:171","msg":"trace[300638069] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15590; }","duration":"207.440052ms","start":"2026-04-14T06:50:38.107688Z","end":"2026-04-14T06:50:38.315128Z","steps":["trace[300638069] 'agreement among raft nodes before linearized reading' (duration: 207.249517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:50:38.317235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.453576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-14T06:50:38.317306Z","caller":"traceutil/trace.go:171","msg":"trace[158050556] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15592; }","duration":"203.535088ms","start":"2026-04-14T06:50:38.113755Z","end":"2026-04-14T06:50:38.31729Z","steps":["trace[158050556] 'agreement among raft nodes before linearized reading' (duration: 203.319432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:50:38.317482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.783501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-14T06:50:38.317519Z","caller":"traceutil/trace.go:171","msg":"trace[974921213] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15592; }","duration":"208.850043ms","start":"2026-04-14T06:50:38.10866Z","end":"2026-04-14T06:50:38.31751Z","steps":["trace[974921213] 'agreement among raft nodes before linearized reading' (duration: 208.645987ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:29.003347Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14896} {"level":"info","ts":"2026-04-14T06:54:29.094367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14896,"took":"88.934197ms","hash":389409332,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":29851648,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T06:54:29.09444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":389409332,"revision":14896,"compact-revision":12723} {"level":"info","ts":"2026-04-14T06:56:43.363923Z","caller":"traceutil/trace.go:171","msg":"trace[1151835015] transaction","detail":"{read_only:false; response_revision:18257; number_of_response:1; }","duration":"163.566431ms","start":"2026-04-14T06:56:43.200317Z","end":"2026-04-14T06:56:43.363883Z","steps":["trace[1151835015] 'process raft request' (duration: 162.961702ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:56:49.310275Z","caller":"traceutil/trace.go:171","msg":"trace[1242030169] transaction","detail":"{read_only:false; response_revision:18290; number_of_response:1; }","duration":"135.989715ms","start":"2026-04-14T06:56:49.174256Z","end":"2026-04-14T06:56:49.310246Z","steps":["trace[1242030169] 'process raft request' (duration: 101.994824ms)","trace[1242030169] 'compare' (duration: 33.476466ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:56:50.283019Z","caller":"traceutil/trace.go:171","msg":"trace[608510253] transaction","detail":"{read_only:false; response_revision:18293; number_of_response:1; }","duration":"204.42881ms","start":"2026-04-14T06:56:50.078552Z","end":"2026-04-14T06:56:50.282981Z","steps":["trace[608510253] 'process raft request' (duration: 204.188614ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:56:50.352083Z","caller":"traceutil/trace.go:171","msg":"trace[1648543586] transaction","detail":"{read_only:false; response_revision:18294; number_of_response:1; }","duration":"261.62416ms","start":"2026-04-14T06:56:50.090431Z","end":"2026-04-14T06:56:50.352055Z","steps":["trace[1648543586] 'process raft request' (duration: 261.392854ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:58:37.299206Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T06:58:37.306258Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-14T06:58:37.306384Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-14T06:59:29.011303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17341} {"level":"info","ts":"2026-04-14T06:59:29.108442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17341,"took":"93.90845ms","hash":3417136605,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":34197504,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-14T06:59:29.108512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3417136605,"revision":17341,"compact-revision":14896} {"level":"info","ts":"2026-04-14T07:02:46.350278Z","caller":"traceutil/trace.go:171","msg":"trace[1866430287] linearizableReadLoop","detail":"{readStateIndex:21813; appliedIndex:21812; }","duration":"124.533543ms","start":"2026-04-14T07:02:46.22572Z","end":"2026-04-14T07:02:46.350253Z","steps":["trace[1866430287] 'read index received' (duration: 124.319187ms)","trace[1866430287] 'applied index is now lower than readState.Index' (duration: 213.546µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:46.350385Z","caller":"traceutil/trace.go:171","msg":"trace[1845035203] transaction","detail":"{read_only:false; response_revision:21096; number_of_response:1; }","duration":"143.605463ms","start":"2026-04-14T07:02:46.206728Z","end":"2026-04-14T07:02:46.350333Z","steps":["trace[1845035203] 'process raft request' (duration: 143.368467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:46.35054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.80371ms","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-04-14T07:02:46.351677Z","caller":"traceutil/trace.go:171","msg":"trace[2115573879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21096; }","duration":"125.987896ms","start":"2026-04-14T07:02:46.225653Z","end":"2026-04-14T07:02:46.351641Z","steps":["trace[2115573879] 'agreement among raft nodes before linearized reading' (duration: 124.701858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:46.643747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.736956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:02:46.643928Z","caller":"traceutil/trace.go:171","msg":"trace[1647204120] transaction","detail":"{read_only:false; response_revision:21098; number_of_response:1; }","duration":"288.576737ms","start":"2026-04-14T07:02:46.355334Z","end":"2026-04-14T07:02:46.643911Z","steps":["trace[1647204120] 'process raft request' (duration: 288.511225ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:46.643973Z","caller":"traceutil/trace.go:171","msg":"trace[446369466] transaction","detail":"{read_only:false; response_revision:21097; number_of_response:1; }","duration":"288.619348ms","start":"2026-04-14T07:02:46.35531Z","end":"2026-04-14T07:02:46.643929Z","steps":["trace[446369466] 'process raft request' (duration: 143.612824ms)","trace[446369466] 'compare' (duration: 144.454459ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:46.664023Z","caller":"traceutil/trace.go:171","msg":"trace[879118812] transaction","detail":"{read_only:false; response_revision:21099; number_of_response:1; }","duration":"308.214434ms","start":"2026-04-14T07:02:46.355769Z","end":"2026-04-14T07:02:46.663983Z","steps":["trace[879118812] 'process raft request' (duration: 308.000118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:46.664191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:46.355751Z","time spent":"308.353727ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:02:46.926439Z","caller":"traceutil/trace.go:171","msg":"trace[746965650] transaction","detail":"{read_only:false; response_revision:21100; number_of_response:1; }","duration":"210.576157ms","start":"2026-04-14T07:02:46.715827Z","end":"2026-04-14T07:02:46.926403Z","steps":["trace[746965650] 'process raft request' (duration: 187.849021ms)","trace[746965650] 'compare' (duration: 22.358015ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:47.001341Z","caller":"traceutil/trace.go:171","msg":"trace[1923625452] linearizableReadLoop","detail":"{readStateIndex:21818; appliedIndex:21816; }","duration":"181.450116ms","start":"2026-04-14T07:02:46.819874Z","end":"2026-04-14T07:02:47.001324Z","steps":["trace[1923625452] 'read index received' (duration: 83.817268ms)","trace[1923625452] 'applied index is now lower than readState.Index' (duration: 97.631747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:47.001498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.619541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:02:47.00155Z","caller":"traceutil/trace.go:171","msg":"trace[1432287501] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:21101; }","duration":"181.683072ms","start":"2026-04-14T07:02:46.81985Z","end":"2026-04-14T07:02:47.001533Z","steps":["trace[1432287501] 'agreement among raft nodes before linearized reading' (duration: 181.572249ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:47.001587Z","caller":"traceutil/trace.go:171","msg":"trace[1828038265] transaction","detail":"{read_only:false; response_revision:21101; number_of_response:1; }","duration":"198.288372ms","start":"2026-04-14T07:02:46.803191Z","end":"2026-04-14T07:02:47.00148Z","steps":["trace[1828038265] 'process raft request' (duration: 197.758847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:47.168563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.814823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:02:47.16869Z","caller":"traceutil/trace.go:171","msg":"trace[1054370380] transaction","detail":"{read_only:false; response_revision:21102; number_of_response:1; }","duration":"161.595273ms","start":"2026-04-14T07:02:47.007073Z","end":"2026-04-14T07:02:47.168668Z","steps":["trace[1054370380] 'process raft request' (duration: 55.588605ms)","trace[1054370380] 'compare' (duration: 105.680428ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:47.447777Z","caller":"traceutil/trace.go:171","msg":"trace[852081327] transaction","detail":"{read_only:false; response_revision:21104; number_of_response:1; }","duration":"263.612626ms","start":"2026-04-14T07:02:47.184137Z","end":"2026-04-14T07:02:47.447749Z","steps":["trace[852081327] 'process raft request' (duration: 189.430346ms)","trace[852081327] 'compare' (duration: 73.991655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:47.866277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.064927ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T07:02:47.866457Z","caller":"traceutil/trace.go:171","msg":"trace[1320892318] linearizableReadLoop","detail":"{readStateIndex:21822; appliedIndex:21821; }","duration":"293.867069ms","start":"2026-04-14T07:02:47.572494Z","end":"2026-04-14T07:02:47.866361Z","steps":["trace[1320892318] 'read index received' (duration: 63.812µs)","trace[1320892318] 'applied index is now lower than readState.Index' (duration: 293.801717ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:47.866572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.076344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:02:47.866642Z","caller":"traceutil/trace.go:171","msg":"trace[1663606926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21104; }","duration":"294.151377ms","start":"2026-04-14T07:02:47.572476Z","end":"2026-04-14T07:02:47.866628Z","steps":["trace[1663606926] 'agreement among raft nodes before linearized reading' (duration: 294.038013ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:47.872218Z","caller":"traceutil/trace.go:171","msg":"trace[865396905] transaction","detail":"{read_only:false; response_revision:21105; number_of_response:1; }","duration":"101.484679ms","start":"2026-04-14T07:02:47.770701Z","end":"2026-04-14T07:02:47.872186Z","steps":["trace[865396905] 'process raft request' (duration: 101.231761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:47.872241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.260593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:02:47.872509Z","caller":"traceutil/trace.go:171","msg":"trace[482306573] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:21105; }","duration":"240.570752ms","start":"2026-04-14T07:02:47.631921Z","end":"2026-04-14T07:02:47.872492Z","steps":["trace[482306573] 'agreement among raft nodes before linearized reading' (duration: 240.256153ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:04:07.884599Z","caller":"traceutil/trace.go:171","msg":"trace[653113203] linearizableReadLoop","detail":"{readStateIndex:22581; appliedIndex:22580; }","duration":"301.253452ms","start":"2026-04-14T07:04:07.583322Z","end":"2026-04-14T07:04:07.884576Z","steps":["trace[653113203] 'read index received' (duration: 301.075867ms)","trace[653113203] 'applied index is now lower than readState.Index' (duration: 176.395µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:04:07.884999Z","caller":"traceutil/trace.go:171","msg":"trace[784403573] transaction","detail":"{read_only:false; response_revision:21837; number_of_response:1; }","duration":"310.686705ms","start":"2026-04-14T07:04:07.574298Z","end":"2026-04-14T07:04:07.884984Z","steps":["trace[784403573] 'process raft request' (duration: 310.1567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:04:07.885189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:04:07.574281Z","time spent":"310.765097ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:04:07.885594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.270022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/octavia-ks-service-nj286\" ","response":"range_response_count:1 size:8127"} {"level":"info","ts":"2026-04-14T07:04:07.885668Z","caller":"traceutil/trace.go:171","msg":"trace[1647366241] range","detail":"{range_begin:/registry/pods/openstack/octavia-ks-service-nj286; range_end:; response_count:1; response_revision:21837; }","duration":"302.370285ms","start":"2026-04-14T07:04:07.583285Z","end":"2026-04-14T07:04:07.885655Z","steps":["trace[1647366241] 'agreement among raft nodes before linearized reading' (duration: 302.180279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:04:07.885716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:04:07.583272Z","time spent":"302.432186ms","remote":"127.0.0.1:38886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":8150,"request content":"key:\"/registry/pods/openstack/octavia-ks-service-nj286\" "} {"level":"info","ts":"2026-04-14T07:04:29.017865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19667} {"level":"info","ts":"2026-04-14T07:04:29.151859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19667,"took":"130.244619ms","hash":1127511703,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":30228480,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T07:04:29.151935Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1127511703,"revision":19667,"compact-revision":17341} {"level":"info","ts":"2026-04-14T07:06:37.9752Z","caller":"traceutil/trace.go:171","msg":"trace[2050932437] transaction","detail":"{read_only:false; response_revision:23555; number_of_response:1; }","duration":"121.66983ms","start":"2026-04-14T07:06:37.853485Z","end":"2026-04-14T07:06:37.975155Z","steps":["trace[2050932437] 'process raft request' (duration: 121.387192ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:06:38.152023Z","caller":"traceutil/trace.go:171","msg":"trace[1899525652] transaction","detail":"{read_only:false; response_revision:23557; number_of_response:1; }","duration":"171.931641ms","start":"2026-04-14T07:06:37.980067Z","end":"2026-04-14T07:06:38.151999Z","steps":["trace[1899525652] 'process raft request' (duration: 171.751566ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:06:38.152219Z","caller":"traceutil/trace.go:171","msg":"trace[1984264291] transaction","detail":"{read_only:false; response_revision:23558; number_of_response:1; }","duration":"171.51495ms","start":"2026-04-14T07:06:37.980679Z","end":"2026-04-14T07:06:38.152194Z","steps":["trace[1984264291] 'process raft request' (duration: 171.284122ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:29.027611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21970} {"level":"info","ts":"2026-04-14T07:09:29.132746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21970,"took":"101.416656ms","hash":482985563,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":43069440,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-14T07:09:29.132819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":482985563,"revision":21970,"compact-revision":19667} {"level":"info","ts":"2026-04-14T07:09:33.776575Z","caller":"traceutil/trace.go:171","msg":"trace[1154899693] linearizableReadLoop","detail":"{readStateIndex:26078; appliedIndex:26077; }","duration":"195.118229ms","start":"2026-04-14T07:09:33.581435Z","end":"2026-04-14T07:09:33.776554Z","steps":["trace[1154899693] 'read index received' (duration: 183.065702ms)","trace[1154899693] 'applied index is now lower than readState.Index' (duration: 12.051717ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:33.776756Z","caller":"traceutil/trace.go:171","msg":"trace[665617465] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"195.391507ms","start":"2026-04-14T07:09:33.58133Z","end":"2026-04-14T07:09:33.776722Z","steps":["trace[665617465] 'process raft request' (duration: 183.159224ms)","trace[665617465] 'compare' (duration: 11.606665ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:09:33.77685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.407669ms","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-14T07:09:33.777445Z","caller":"traceutil/trace.go:171","msg":"trace[1715475858] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25247; }","duration":"196.038927ms","start":"2026-04-14T07:09:33.58139Z","end":"2026-04-14T07:09:33.777429Z","steps":["trace[1715475858] 'agreement among raft nodes before linearized reading' (duration: 195.323045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:33.838561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.98566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:33.838718Z","caller":"traceutil/trace.go:171","msg":"trace[1321478314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25249; }","duration":"105.223377ms","start":"2026-04-14T07:09:33.733465Z","end":"2026-04-14T07:09:33.838689Z","steps":["trace[1321478314] 'agreement among raft nodes before linearized reading' (duration: 104.947679ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:33.838617Z","caller":"traceutil/trace.go:171","msg":"trace[2130756252] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"256.665236ms","start":"2026-04-14T07:09:33.581903Z","end":"2026-04-14T07:09:33.838568Z","steps":["trace[2130756252] 'process raft request' (duration: 256.174761ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:33.838658Z","caller":"traceutil/trace.go:171","msg":"trace[1859692128] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"158.944336ms","start":"2026-04-14T07:09:33.679687Z","end":"2026-04-14T07:09:33.838632Z","steps":["trace[1859692128] 'process raft request' (duration: 158.538263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:08.821502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.831431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:08.821601Z","caller":"traceutil/trace.go:171","msg":"trace[1131806534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25672; }","duration":"248.013476ms","start":"2026-04-14T07:10:08.57357Z","end":"2026-04-14T07:10:08.821583Z","steps":["trace[1131806534] 'range keys from in-memory index tree' (duration: 247.746248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:08.822099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.407061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:08.822187Z","caller":"traceutil/trace.go:171","msg":"trace[875841703] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25672; }","duration":"175.522365ms","start":"2026-04-14T07:10:08.646642Z","end":"2026-04-14T07:10:08.822165Z","steps":["trace[875841703] 'count revisions from in-memory index tree' (duration: 175.313199ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:09.068146Z","caller":"traceutil/trace.go:171","msg":"trace[1857661480] linearizableReadLoop","detail":"{readStateIndex:26518; appliedIndex:26517; }","duration":"241.601372ms","start":"2026-04-14T07:10:08.826521Z","end":"2026-04-14T07:10:09.068122Z","steps":["trace[1857661480] 'read index received' (duration: 3.182702ms)","trace[1857661480] 'applied index is now lower than readState.Index' (duration: 238.41713ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:09.068341Z","caller":"traceutil/trace.go:171","msg":"trace[1589864953] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"241.951502ms","start":"2026-04-14T07:10:08.826378Z","end":"2026-04-14T07:10:09.068329Z","steps":["trace[1589864953] 'process raft request' (duration: 241.591781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:09.068716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.175739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:09.06881Z","caller":"traceutil/trace.go:171","msg":"trace[1706387404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25674; }","duration":"242.304953ms","start":"2026-04-14T07:10:08.826485Z","end":"2026-04-14T07:10:09.06879Z","steps":["trace[1706387404] 'agreement among raft nodes before linearized reading' (duration: 242.145327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:09.072061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.511419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-04-14T07:10:09.072583Z","caller":"traceutil/trace.go:171","msg":"trace[1886345771] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:25676; }","duration":"224.059085ms","start":"2026-04-14T07:10:08.848506Z","end":"2026-04-14T07:10:09.072566Z","steps":["trace[1886345771] 'agreement among raft nodes before linearized reading' (duration: 223.425077ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:09.072189Z","caller":"traceutil/trace.go:171","msg":"trace[1007828024] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"111.798916ms","start":"2026-04-14T07:10:08.960363Z","end":"2026-04-14T07:10:09.072162Z","steps":["trace[1007828024] 'process raft request' (duration: 111.409585ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:12.937462Z","caller":"traceutil/trace.go:171","msg":"trace[93691007] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"105.974258ms","start":"2026-04-14T07:10:12.83147Z","end":"2026-04-14T07:10:12.937444Z","steps":["trace[93691007] 'process raft request' (duration: 105.845183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:13.119995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.665494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:13.120105Z","caller":"traceutil/trace.go:171","msg":"trace[1307794004] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"279.478665ms","start":"2026-04-14T07:10:12.840607Z","end":"2026-04-14T07:10:13.120086Z","steps":["trace[1307794004] 'process raft request' (duration: 168.644296ms)","trace[1307794004] 'compare' (duration: 110.569551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:13.449547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.057699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:13.449645Z","caller":"traceutil/trace.go:171","msg":"trace[1637391133] linearizableReadLoop","detail":"{readStateIndex:26566; appliedIndex:26565; }","duration":"231.957202ms","start":"2026-04-14T07:10:13.217672Z","end":"2026-04-14T07:10:13.449629Z","steps":["trace[1637391133] 'read index received' (duration: 63.767449ms)","trace[1637391133] 'applied index is now lower than readState.Index' (duration: 168.188223ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:13.449774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.096037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:13.449759Z","caller":"traceutil/trace.go:171","msg":"trace[456453535] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"234.6384ms","start":"2026-04-14T07:10:13.215081Z","end":"2026-04-14T07:10:13.44972Z","steps":["trace[456453535] 'process raft request' (duration: 66.332703ms)","trace[456453535] 'compare' (duration: 167.872794ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:13.449812Z","caller":"traceutil/trace.go:171","msg":"trace[1454641485] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25721; }","duration":"232.162818ms","start":"2026-04-14T07:10:13.217638Z","end":"2026-04-14T07:10:13.449801Z","steps":["trace[1454641485] 'agreement among raft nodes before linearized reading' (duration: 232.066875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:13.450011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.725906ms","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-04-14T07:10:13.450091Z","caller":"traceutil/trace.go:171","msg":"trace[1127691507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25721; }","duration":"205.859201ms","start":"2026-04-14T07:10:13.244212Z","end":"2026-04-14T07:10:13.450072Z","steps":["trace[1127691507] 'agreement among raft nodes before linearized reading' (duration: 205.647864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:13.450228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.238026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:13.450263Z","caller":"traceutil/trace.go:171","msg":"trace[218073444] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25721; }","duration":"106.306098ms","start":"2026-04-14T07:10:13.343947Z","end":"2026-04-14T07:10:13.450253Z","steps":["trace[218073444] 'agreement among raft nodes before linearized reading' (duration: 106.254346ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:14.014538Z","caller":"traceutil/trace.go:171","msg":"trace[312212528] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"558.765143ms","start":"2026-04-14T07:10:13.45574Z","end":"2026-04-14T07:10:14.014505Z","steps":["trace[312212528] 'process raft request' (duration: 558.573268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:14.014794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:13.455717Z","time spent":"558.99127ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:14.027852Z","caller":"traceutil/trace.go:171","msg":"trace[571889230] linearizableReadLoop","detail":"{readStateIndex:26569; appliedIndex:26566; }","duration":"457.77425ms","start":"2026-04-14T07:10:13.57006Z","end":"2026-04-14T07:10:14.027835Z","steps":["trace[571889230] 'read index received' (duration: 444.25612ms)","trace[571889230] 'applied index is now lower than readState.Index' (duration: 13.51701ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:14.028069Z","caller":"traceutil/trace.go:171","msg":"trace[733520573] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"572.150269ms","start":"2026-04-14T07:10:13.455911Z","end":"2026-04-14T07:10:14.028061Z","steps":["trace[733520573] 'process raft request' (duration: 571.739558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:14.02815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:13.455899Z","time spent":"572.19218ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:14.028327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.463167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:14.028355Z","caller":"traceutil/trace.go:171","msg":"trace[318766215] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25726; }","duration":"138.490257ms","start":"2026-04-14T07:10:13.889854Z","end":"2026-04-14T07:10:14.028344Z","steps":["trace[318766215] 'agreement among raft nodes before linearized reading' (duration: 138.447306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:14.028351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.278284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:14.028419Z","caller":"traceutil/trace.go:171","msg":"trace[1135220141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25726; }","duration":"458.392397ms","start":"2026-04-14T07:10:13.570008Z","end":"2026-04-14T07:10:14.0284Z","steps":["trace[1135220141] 'agreement among raft nodes before linearized reading' (duration: 458.267954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:14.028453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:13.569985Z","time spent":"458.462059ms","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T07:10:14.028607Z","caller":"traceutil/trace.go:171","msg":"trace[1906681260] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"572.114259ms","start":"2026-04-14T07:10:13.456484Z","end":"2026-04-14T07:10:14.028598Z","steps":["trace[1906681260] 'process raft request' (duration: 571.265145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:14.028667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:13.456476Z","time spent":"572.144289ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:14.02876Z","caller":"traceutil/trace.go:171","msg":"trace[792263176] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"405.905823ms","start":"2026-04-14T07:10:13.622849Z","end":"2026-04-14T07:10:14.028754Z","steps":["trace[792263176] 'process raft request' (duration: 404.931925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:14.028791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:13.622826Z","time spent":"405.944394ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:14.028831Z","caller":"traceutil/trace.go:171","msg":"trace[1600285486] transaction","detail":"{read_only:false; response_revision:25726; number_of_response:1; }","duration":"225.341242ms","start":"2026-04-14T07:10:13.803485Z","end":"2026-04-14T07:10:14.028826Z","steps":["trace[1600285486] 'process raft request' (duration: 224.319863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:14.355239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.307902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:14.355335Z","caller":"traceutil/trace.go:171","msg":"trace[1966450678] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"321.825816ms","start":"2026-04-14T07:10:14.03348Z","end":"2026-04-14T07:10:14.355306Z","steps":["trace[1966450678] 'process raft request' (duration: 140.38248ms)","trace[1966450678] 'compare' (duration: 181.109187ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:14.355381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:14.033462Z","time spent":"321.896319ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:14.73632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.573005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:14.736586Z","caller":"traceutil/trace.go:171","msg":"trace[607167073] transaction","detail":"{read_only:false; response_revision:25729; number_of_response:1; }","duration":"640.970685ms","start":"2026-04-14T07:10:14.09559Z","end":"2026-04-14T07:10:14.736561Z","steps":["trace[607167073] 'process raft request' (duration: 640.833551ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:14.736606Z","caller":"traceutil/trace.go:171","msg":"trace[1316875754] transaction","detail":"{read_only:false; response_revision:25728; number_of_response:1; }","duration":"689.706592ms","start":"2026-04-14T07:10:14.046875Z","end":"2026-04-14T07:10:14.736581Z","steps":["trace[1316875754] 'process raft request' (duration: 433.604771ms)","trace[1316875754] 'compare' (duration: 253.251258ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:14.736618Z","caller":"traceutil/trace.go:171","msg":"trace[1913250045] linearizableReadLoop","detail":"{readStateIndex:26573; appliedIndex:26571; }","duration":"648.030979ms","start":"2026-04-14T07:10:14.088563Z","end":"2026-04-14T07:10:14.736594Z","steps":["trace[1913250045] 'read index received' (duration: 85.297701ms)","trace[1913250045] 'applied index is now lower than readState.Index' (duration: 562.731138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:14.736667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:14.095556Z","time spent":"641.075998ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:14.736702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:14.046854Z","time spent":"689.809924ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:14.736827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.256216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-14T07:10:14.736841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.762772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:14.736869Z","caller":"traceutil/trace.go:171","msg":"trace[339549674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25730; }","duration":"648.327797ms","start":"2026-04-14T07:10:14.088531Z","end":"2026-04-14T07:10:14.736858Z","steps":["trace[339549674] 'agreement among raft nodes before linearized reading' (duration: 648.188763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:14.736946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:14.088515Z","time spent":"648.38548ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T07:10:14.736871Z","caller":"traceutil/trace.go:171","msg":"trace[1994056574] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25730; }","duration":"175.828554ms","start":"2026-04-14T07:10:14.561033Z","end":"2026-04-14T07:10:14.736862Z","steps":["trace[1994056574] 'agreement among raft nodes before linearized reading' (duration: 175.779732ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:14.737494Z","caller":"traceutil/trace.go:171","msg":"trace[288122241] transaction","detail":"{read_only:false; response_revision:25730; number_of_response:1; }","duration":"252.461264ms","start":"2026-04-14T07:10:14.484079Z","end":"2026-04-14T07:10:14.73654Z","steps":["trace[288122241] 'process raft request' (duration: 252.418643ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:15.499285Z","caller":"traceutil/trace.go:171","msg":"trace[1038345755] transaction","detail":"{read_only:false; response_revision:25733; number_of_response:1; }","duration":"311.833738ms","start":"2026-04-14T07:10:15.187429Z","end":"2026-04-14T07:10:15.499263Z","steps":["trace[1038345755] 'process raft request' (duration: 311.500938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:15.49942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:15.187408Z","time spent":"311.9428ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:23.181173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.814218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:23.181245Z","caller":"traceutil/trace.go:171","msg":"trace[427145470] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25794; }","duration":"144.940252ms","start":"2026-04-14T07:10:23.03629Z","end":"2026-04-14T07:10:23.18123Z","steps":["trace[427145470] 'count revisions from in-memory index tree' (duration: 144.740847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:23.181476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.270577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:23.181542Z","caller":"traceutil/trace.go:171","msg":"trace[421641310] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25794; }","duration":"175.385751ms","start":"2026-04-14T07:10:23.006141Z","end":"2026-04-14T07:10:23.181527Z","steps":["trace[421641310] 'count revisions from in-memory index tree' (duration: 175.206505ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:23.645016Z","caller":"traceutil/trace.go:171","msg":"trace[886130424] linearizableReadLoop","detail":"{readStateIndex:26643; appliedIndex:26642; }","duration":"165.67277ms","start":"2026-04-14T07:10:23.479315Z","end":"2026-04-14T07:10:23.644988Z","steps":["trace[886130424] 'read index received' (duration: 165.503676ms)","trace[886130424] 'applied index is now lower than readState.Index' (duration: 167.624µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:23.645134Z","caller":"traceutil/trace.go:171","msg":"trace[1273526811] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"214.780608ms","start":"2026-04-14T07:10:23.430307Z","end":"2026-04-14T07:10:23.645088Z","steps":["trace[1273526811] 'process raft request' (duration: 214.50514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:23.645176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.829805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:23.64541Z","caller":"traceutil/trace.go:171","msg":"trace[1464597851] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25796; }","duration":"166.104483ms","start":"2026-04-14T07:10:23.479276Z","end":"2026-04-14T07:10:23.645381Z","steps":["trace[1464597851] 'agreement among raft nodes before linearized reading' (duration: 165.835975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:23.696623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.613405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:23.696697Z","caller":"traceutil/trace.go:171","msg":"trace[1109875721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25798; }","duration":"125.730148ms","start":"2026-04-14T07:10:23.570951Z","end":"2026-04-14T07:10:23.696682Z","steps":["trace[1109875721] 'agreement among raft nodes before linearized reading' (duration: 125.568134ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:23.696896Z","caller":"traceutil/trace.go:171","msg":"trace[381303910] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"149.091522ms","start":"2026-04-14T07:10:23.547795Z","end":"2026-04-14T07:10:23.696886Z","steps":["trace[381303910] 'process raft request' (duration: 148.67375ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:23.696935Z","caller":"traceutil/trace.go:171","msg":"trace[1459667390] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"149.348579ms","start":"2026-04-14T07:10:23.547557Z","end":"2026-04-14T07:10:23.696906Z","steps":["trace[1459667390] 'process raft request' (duration: 148.802203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:23.976296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.772385ms","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-04-14T07:10:23.976405Z","caller":"traceutil/trace.go:171","msg":"trace[475142402] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25798; }","duration":"173.947269ms","start":"2026-04-14T07:10:23.802432Z","end":"2026-04-14T07:10:23.976379Z","steps":["trace[475142402] 'count revisions from in-memory index tree' (duration: 173.604359ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:24.26929Z","caller":"traceutil/trace.go:171","msg":"trace[222355995] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"195.883642ms","start":"2026-04-14T07:10:24.073386Z","end":"2026-04-14T07:10:24.269269Z","steps":["trace[222355995] 'process raft request' (duration: 180.373394ms)","trace[222355995] 'compare' (duration: 14.928751ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:24.269337Z","caller":"traceutil/trace.go:171","msg":"trace[1012100547] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"192.630819ms","start":"2026-04-14T07:10:24.076655Z","end":"2026-04-14T07:10:24.269286Z","steps":["trace[1012100547] 'process raft request' (duration: 192.337869ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:24.269107Z","caller":"traceutil/trace.go:171","msg":"trace[1221449107] linearizableReadLoop","detail":"{readStateIndex:26647; appliedIndex:26645; }","duration":"193.296548ms","start":"2026-04-14T07:10:24.075788Z","end":"2026-04-14T07:10:24.269085Z","steps":["trace[1221449107] 'read index received' (duration: 37.688567ms)","trace[1221449107] 'applied index is now lower than readState.Index' (duration: 155.606851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:24.269679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.863884ms","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-04-14T07:10:24.269761Z","caller":"traceutil/trace.go:171","msg":"trace[264570907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25801; }","duration":"193.972546ms","start":"2026-04-14T07:10:24.075761Z","end":"2026-04-14T07:10:24.269733Z","steps":["trace[264570907] 'agreement among raft nodes before linearized reading' (duration: 193.721649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:24.269783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.86794ms","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-04-14T07:10:24.269852Z","caller":"traceutil/trace.go:171","msg":"trace[1520404981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25801; }","duration":"179.974423ms","start":"2026-04-14T07:10:24.08986Z","end":"2026-04-14T07:10:24.269835Z","steps":["trace[1520404981] 'agreement among raft nodes before linearized reading' (duration: 179.691735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:24.270036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.185927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-04-14T07:10:24.270154Z","caller":"traceutil/trace.go:171","msg":"trace[1484934618] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25801; }","duration":"191.345831ms","start":"2026-04-14T07:10:24.078774Z","end":"2026-04-14T07:10:24.27012Z","steps":["trace[1484934618] 'agreement among raft nodes before linearized reading' (duration: 190.747644ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:24.581197Z","caller":"traceutil/trace.go:171","msg":"trace[936522468] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"307.204615ms","start":"2026-04-14T07:10:24.273937Z","end":"2026-04-14T07:10:24.581142Z","steps":["trace[936522468] 'process raft request' (duration: 291.296056ms)","trace[936522468] 'compare' (duration: 15.736524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:24.581357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:24.273924Z","time spent":"307.354119ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:24.58216Z","caller":"traceutil/trace.go:171","msg":"trace[149250356] linearizableReadLoop","detail":"{readStateIndex:26650; appliedIndex:26648; }","duration":"182.551357ms","start":"2026-04-14T07:10:24.399589Z","end":"2026-04-14T07:10:24.582141Z","steps":["trace[149250356] 'read index received' (duration: 165.841755ms)","trace[149250356] 'applied index is now lower than readState.Index' (duration: 16.708422ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:24.582265Z","caller":"traceutil/trace.go:171","msg":"trace[1141664283] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"307.942536ms","start":"2026-04-14T07:10:24.274265Z","end":"2026-04-14T07:10:24.582208Z","steps":["trace[1141664283] 'process raft request' (duration: 307.768261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:24.582337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.744383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T07:10:24.582385Z","caller":"traceutil/trace.go:171","msg":"trace[1959319418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25803; }","duration":"182.841916ms","start":"2026-04-14T07:10:24.399531Z","end":"2026-04-14T07:10:24.582373Z","steps":["trace[1959319418] 'agreement among raft nodes before linearized reading' (duration: 182.695902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:24.582513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:24.274239Z","time spent":"308.150981ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:24.759497Z","caller":"traceutil/trace.go:171","msg":"trace[933974964] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"173.080915ms","start":"2026-04-14T07:10:24.586341Z","end":"2026-04-14T07:10:24.759422Z","steps":["trace[933974964] 'process raft request' (duration: 160.198272ms)","trace[933974964] 'compare' (duration: 12.657455ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:24.932305Z","caller":"traceutil/trace.go:171","msg":"trace[1707202510] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"151.608925ms","start":"2026-04-14T07:10:24.780674Z","end":"2026-04-14T07:10:24.932283Z","steps":["trace[1707202510] 'process raft request' (duration: 138.232149ms)","trace[1707202510] 'compare' (duration: 13.283853ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:25.064873Z","caller":"traceutil/trace.go:171","msg":"trace[1500371125] linearizableReadLoop","detail":"{readStateIndex:26654; appliedIndex:26651; }","duration":"231.232013ms","start":"2026-04-14T07:10:24.833617Z","end":"2026-04-14T07:10:25.064849Z","steps":["trace[1500371125] 'read index received' (duration: 85.290701ms)","trace[1500371125] 'applied index is now lower than readState.Index' (duration: 145.940182ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:25.064918Z","caller":"traceutil/trace.go:171","msg":"trace[831962232] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"282.607545ms","start":"2026-04-14T07:10:24.782266Z","end":"2026-04-14T07:10:25.064873Z","steps":["trace[831962232] 'process raft request' (duration: 262.945178ms)","trace[831962232] 'compare' (duration: 19.287136ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:25.064954Z","caller":"traceutil/trace.go:171","msg":"trace[364877551] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"282.43364ms","start":"2026-04-14T07:10:24.782493Z","end":"2026-04-14T07:10:25.064926Z","steps":["trace[364877551] 'process raft request' (duration: 282.240574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:25.065123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.424909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:25.065187Z","caller":"traceutil/trace.go:171","msg":"trace[762287725] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25807; }","duration":"231.608534ms","start":"2026-04-14T07:10:24.833561Z","end":"2026-04-14T07:10:25.065169Z","steps":["trace[762287725] 'agreement among raft nodes before linearized reading' (duration: 231.430469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:25.065332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.060697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-14T07:10:25.065429Z","caller":"traceutil/trace.go:171","msg":"trace[635884867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25807; }","duration":"196.208782ms","start":"2026-04-14T07:10:24.8692Z","end":"2026-04-14T07:10:25.065409Z","steps":["trace[635884867] 'agreement among raft nodes before linearized reading' (duration: 195.978775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:25.307785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.397288ms","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-04-14T07:10:25.307887Z","caller":"traceutil/trace.go:171","msg":"trace[444121957] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25808; }","duration":"100.561812ms","start":"2026-04-14T07:10:25.207305Z","end":"2026-04-14T07:10:25.307867Z","steps":["trace[444121957] 'range keys from in-memory index tree' (duration: 100.285614ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:25.455626Z","caller":"traceutil/trace.go:171","msg":"trace[2135350424] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"122.113784ms","start":"2026-04-14T07:10:25.333488Z","end":"2026-04-14T07:10:25.455602Z","steps":["trace[2135350424] 'process raft request' (duration: 121.947639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:26.07295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.668741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:26.07301Z","caller":"traceutil/trace.go:171","msg":"trace[901042455] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25811; }","duration":"164.777184ms","start":"2026-04-14T07:10:25.908223Z","end":"2026-04-14T07:10:26.073Z","steps":["trace[901042455] 'count revisions from in-memory index tree' (duration: 164.578418ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:26.357482Z","caller":"traceutil/trace.go:171","msg":"trace[564602497] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"235.881306ms","start":"2026-04-14T07:10:26.121578Z","end":"2026-04-14T07:10:26.35746Z","steps":["trace[564602497] 'process raft request' (duration: 235.730571ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:26.498266Z","caller":"traceutil/trace.go:171","msg":"trace[1029193684] linearizableReadLoop","detail":"{readStateIndex:26661; appliedIndex:26658; }","duration":"153.934743ms","start":"2026-04-14T07:10:26.3443Z","end":"2026-04-14T07:10:26.498235Z","steps":["trace[1029193684] 'read index received' (duration: 13.013587ms)","trace[1029193684] 'applied index is now lower than readState.Index' (duration: 140.920236ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:26.498313Z","caller":"traceutil/trace.go:171","msg":"trace[476542492] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"223.291343ms","start":"2026-04-14T07:10:26.274971Z","end":"2026-04-14T07:10:26.498262Z","steps":["trace[476542492] 'process raft request' (duration: 223.070426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:26.498731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.419436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:26.498772Z","caller":"traceutil/trace.go:171","msg":"trace[842288287] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25814; }","duration":"154.522719ms","start":"2026-04-14T07:10:26.34424Z","end":"2026-04-14T07:10:26.498763Z","steps":["trace[842288287] 'agreement among raft nodes before linearized reading' (duration: 154.082246ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:26.498663Z","caller":"traceutil/trace.go:171","msg":"trace[1830763093] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"223.026645ms","start":"2026-04-14T07:10:26.275609Z","end":"2026-04-14T07:10:26.498635Z","steps":["trace[1830763093] 'process raft request' (duration: 222.581463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:35.621452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872061184738664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T07:10:35.731681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.823542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T07:10:35.731713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.055795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-04-14T07:10:35.731759Z","caller":"traceutil/trace.go:171","msg":"trace[1298969493] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25937; }","duration":"696.944056ms","start":"2026-04-14T07:10:35.034795Z","end":"2026-04-14T07:10:35.731739Z","steps":["trace[1298969493] 'count revisions from in-memory index tree' (duration: 696.74042ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:35.731776Z","caller":"traceutil/trace.go:171","msg":"trace[1001601958] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25937; }","duration":"658.152247ms","start":"2026-04-14T07:10:35.073607Z","end":"2026-04-14T07:10:35.73176Z","steps":["trace[1001601958] 'range keys from in-memory index tree' (duration: 657.858599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:35.731795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.034778Z","time spent":"697.007687ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:10:35.731811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.073591Z","time spent":"658.212019ms","remote":"127.0.0.1:38854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-04-14T07:10:35.98717Z","caller":"traceutil/trace.go:171","msg":"trace[1071691545] transaction","detail":"{read_only:false; response_revision:25938; number_of_response:1; }","duration":"871.283908ms","start":"2026-04-14T07:10:35.115863Z","end":"2026-04-14T07:10:35.987147Z","steps":["trace[1071691545] 'process raft request' (duration: 871.121473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:35.987316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.115836Z","time spent":"871.404023ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:36.001264Z","caller":"traceutil/trace.go:171","msg":"trace[1570414850] transaction","detail":"{read_only:false; response_revision:25941; number_of_response:1; }","duration":"264.335419ms","start":"2026-04-14T07:10:35.736905Z","end":"2026-04-14T07:10:36.001241Z","steps":["trace[1570414850] 'process raft request' (duration: 264.274228ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:36.001278Z","caller":"traceutil/trace.go:171","msg":"trace[798340779] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"494.758481ms","start":"2026-04-14T07:10:35.50649Z","end":"2026-04-14T07:10:36.001248Z","steps":["trace[798340779] 'process raft request' (duration: 494.650137ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:36.001313Z","caller":"traceutil/trace.go:171","msg":"trace[1829755344] transaction","detail":"{read_only:false; response_revision:25939; number_of_response:1; }","duration":"844.154096ms","start":"2026-04-14T07:10:35.157093Z","end":"2026-04-14T07:10:36.001247Z","steps":["trace[1829755344] 'process raft request' (duration: 843.911218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.001415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.506452Z","time spent":"494.891314ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:36.001505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.157068Z","time spent":"844.331911ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:36.001947Z","caller":"traceutil/trace.go:171","msg":"trace[49798083] transaction","detail":"{read_only:false; response_revision:25942; number_of_response:1; }","duration":"256.790822ms","start":"2026-04-14T07:10:35.74514Z","end":"2026-04-14T07:10:36.001931Z","steps":["trace[49798083] 'process raft request' (duration: 256.06662ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:36.00185Z","caller":"traceutil/trace.go:171","msg":"trace[1632793706] linearizableReadLoop","detail":"{readStateIndex:26790; appliedIndex:26787; }","duration":"881.329558ms","start":"2026-04-14T07:10:35.120507Z","end":"2026-04-14T07:10:36.001837Z","steps":["trace[1632793706] 'read index received' (duration: 866.408357ms)","trace[1632793706] 'applied index is now lower than readState.Index' (duration: 14.920131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:36.002116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.858795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:36.002152Z","caller":"traceutil/trace.go:171","msg":"trace[255697288] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25942; }","duration":"880.922597ms","start":"2026-04-14T07:10:35.121217Z","end":"2026-04-14T07:10:36.00214Z","steps":["trace[255697288] 'agreement among raft nodes before linearized reading' (duration: 880.866665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.002197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.1212Z","time spent":"880.985378ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-04-14T07:10:36.002272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.466513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:36.002332Z","caller":"traceutil/trace.go:171","msg":"trace[2061396206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25942; }","duration":"431.570777ms","start":"2026-04-14T07:10:35.570747Z","end":"2026-04-14T07:10:36.002318Z","steps":["trace[2061396206] 'agreement among raft nodes before linearized reading' (duration: 431.462064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.002375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.570706Z","time spent":"431.660669ms","remote":"127.0.0.1:38728","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-04-14T07:10:36.002434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.732469ms","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-14T07:10:36.002468Z","caller":"traceutil/trace.go:171","msg":"trace[1018893448] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25942; }","duration":"582.796541ms","start":"2026-04-14T07:10:35.419664Z","end":"2026-04-14T07:10:36.00246Z","steps":["trace[1018893448] 'agreement among raft nodes before linearized reading' (duration: 582.685748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.002492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.419644Z","time spent":"582.843393ms","remote":"127.0.0.1:38886","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-14T07:10:36.002714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.999773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-14T07:10:36.002715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.469789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bf8f0f33-cf39-4e72-8c54-87225896b75e\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-04-14T07:10:36.002744Z","caller":"traceutil/trace.go:171","msg":"trace[1994733526] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25942; }","duration":"613.076526ms","start":"2026-04-14T07:10:35.389661Z","end":"2026-04-14T07:10:36.002738Z","steps":["trace[1994733526] 'agreement among raft nodes before linearized reading' (duration: 612.997944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.002764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.389635Z","time spent":"613.123347ms","remote":"127.0.0.1:36326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:10:36.002761Z","caller":"traceutil/trace.go:171","msg":"trace[572551302] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bf8f0f33-cf39-4e72-8c54-87225896b75e; range_end:; response_count:1; response_revision:25942; }","duration":"267.578752ms","start":"2026-04-14T07:10:35.735166Z","end":"2026-04-14T07:10:36.002745Z","steps":["trace[572551302] 'agreement among raft nodes before linearized reading' (duration: 267.377967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.002964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.937253ms","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-04-14T07:10:36.002991Z","caller":"traceutil/trace.go:171","msg":"trace[1940557867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25942; }","duration":"823.006855ms","start":"2026-04-14T07:10:35.179978Z","end":"2026-04-14T07:10:36.002985Z","steps":["trace[1940557867] 'agreement among raft nodes before linearized reading' (duration: 822.939183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.003009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.179954Z","time spent":"823.051287ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T07:10:36.003103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.896812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-14T07:10:36.003162Z","caller":"traceutil/trace.go:171","msg":"trace[1377666281] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8; range_end:; response_count:1; response_revision:25942; }","duration":"285.982584ms","start":"2026-04-14T07:10:35.717164Z","end":"2026-04-14T07:10:36.003147Z","steps":["trace[1377666281] 'agreement among raft nodes before linearized reading' (duration: 285.83631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.003128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.039606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:10:36.003512Z","caller":"traceutil/trace.go:171","msg":"trace[892319944] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25942; }","duration":"858.445067ms","start":"2026-04-14T07:10:35.145058Z","end":"2026-04-14T07:10:36.003503Z","steps":["trace[892319944] 'agreement among raft nodes before linearized reading' (duration: 858.039625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.00354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.145043Z","time spent":"858.488649ms","remote":"127.0.0.1:38992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":18,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:10:36.008346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.840037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210990"} {"level":"info","ts":"2026-04-14T07:10:36.008407Z","caller":"traceutil/trace.go:171","msg":"trace[1053469268] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:25942; }","duration":"887.94914ms","start":"2026-04-14T07:10:35.120445Z","end":"2026-04-14T07:10:36.008394Z","steps":["trace[1053469268] 'agreement among raft nodes before linearized reading' (duration: 881.531934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.008436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.120418Z","time spent":"888.010862ms","remote":"127.0.0.1:38802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2211013,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-04-14T07:10:36.470814Z","caller":"traceutil/trace.go:171","msg":"trace[568125806] linearizableReadLoop","detail":"{readStateIndex:26793; appliedIndex:26792; }","duration":"462.094468ms","start":"2026-04-14T07:10:36.008695Z","end":"2026-04-14T07:10:36.470789Z","steps":["trace[568125806] 'read index received' (duration: 451.915944ms)","trace[568125806] 'applied index is now lower than readState.Index' (duration: 10.177254ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:36.47084Z","caller":"traceutil/trace.go:171","msg":"trace[885662744] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"462.948692ms","start":"2026-04-14T07:10:36.007854Z","end":"2026-04-14T07:10:36.470803Z","steps":["trace[885662744] 'process raft request' (duration: 452.870932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.471015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.007835Z","time spent":"463.084676ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:36.471128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.588529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:36.471228Z","caller":"traceutil/trace.go:171","msg":"trace[1860832793] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25943; }","duration":"456.750864ms","start":"2026-04-14T07:10:36.01445Z","end":"2026-04-14T07:10:36.471201Z","steps":["trace[1860832793] 'agreement among raft nodes before linearized reading' (duration: 456.539768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.471286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.01441Z","time spent":"456.860487ms","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-04-14T07:10:36.471322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.01391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-04-14T07:10:36.471365Z","caller":"traceutil/trace.go:171","msg":"trace[243227950] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:25943; }","duration":"423.105272ms","start":"2026-04-14T07:10:36.048246Z","end":"2026-04-14T07:10:36.471352Z","steps":["trace[243227950] 'agreement among raft nodes before linearized reading' (duration: 423.007779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.471394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.048218Z","time spent":"423.166104ms","remote":"127.0.0.1:38796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1841,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-04-14T07:10:36.471043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.329015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18542"} {"level":"info","ts":"2026-04-14T07:10:36.471444Z","caller":"traceutil/trace.go:171","msg":"trace[482762155] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25943; }","duration":"462.754857ms","start":"2026-04-14T07:10:36.008668Z","end":"2026-04-14T07:10:36.471423Z","steps":["trace[482762155] 'agreement among raft nodes before linearized reading' (duration: 462.253623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.471503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.008656Z","time spent":"462.827999ms","remote":"127.0.0.1:38882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18565,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-14T07:10:37.089866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.65934ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:37.089982Z","caller":"traceutil/trace.go:171","msg":"trace[1859232235] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25943; }","duration":"386.788063ms","start":"2026-04-14T07:10:36.703174Z","end":"2026-04-14T07:10:37.089963Z","steps":["trace[1859232235] 'range keys from in-memory index tree' (duration: 386.642979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.089968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.615076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:37.090058Z","caller":"traceutil/trace.go:171","msg":"trace[154699926] linearizableReadLoop","detail":"{readStateIndex:26794; appliedIndex:26793; }","duration":"612.502479ms","start":"2026-04-14T07:10:36.477543Z","end":"2026-04-14T07:10:37.090045Z","steps":["trace[154699926] 'read index received' (duration: 319.75617ms)","trace[154699926] 'applied index is now lower than readState.Index' (duration: 292.745719ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:37.0901Z","caller":"traceutil/trace.go:171","msg":"trace[1921231509] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"614.595479ms","start":"2026-04-14T07:10:36.475498Z","end":"2026-04-14T07:10:37.090093Z","steps":["trace[1921231509] 'process raft request' (duration: 321.800059ms)","trace[1921231509] 'compare' (duration: 292.44589ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:37.090129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.475471Z","time spent":"614.642731ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:37.090222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.662963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:37.090282Z","caller":"traceutil/trace.go:171","msg":"trace[2023042548] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25944; }","duration":"612.758496ms","start":"2026-04-14T07:10:36.477506Z","end":"2026-04-14T07:10:37.090265Z","steps":["trace[2023042548] 'agreement among raft nodes before linearized reading' (duration: 612.638693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.090324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.47749Z","time spent":"612.821897ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-04-14T07:10:37.786138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.511072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:37.786445Z","caller":"traceutil/trace.go:171","msg":"trace[1048200061] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"1.282519079s","start":"2026-04-14T07:10:36.503889Z","end":"2026-04-14T07:10:37.786408Z","steps":["trace[1048200061] 'process raft request' (duration: 935.659997ms)","trace[1048200061] 'compare' (duration: 345.952125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:37.786561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.503866Z","time spent":"1.282642442s","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T07:10:37.786548Z","caller":"traceutil/trace.go:171","msg":"trace[731647566] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"1.241454233s","start":"2026-04-14T07:10:36.545052Z","end":"2026-04-14T07:10:37.786507Z","steps":["trace[731647566] 'process raft request' (duration: 1.241280618s)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:37.786683Z","caller":"traceutil/trace.go:171","msg":"trace[704019180] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"1.241408952s","start":"2026-04-14T07:10:36.545167Z","end":"2026-04-14T07:10:37.786576Z","steps":["trace[704019180] 'process raft request' (duration: 1.241268017s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.786748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.54502Z","time spent":"1.241640538s","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:37.786859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.545158Z","time spent":"1.241594417s","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:37.786924Z","caller":"traceutil/trace.go:171","msg":"trace[727085921] linearizableReadLoop","detail":"{readStateIndex:26798; appliedIndex:26794; }","duration":"696.844603ms","start":"2026-04-14T07:10:37.090072Z","end":"2026-04-14T07:10:37.786916Z","steps":["trace[727085921] 'read index received' (duration: 349.59171ms)","trace[727085921] 'applied index is now lower than readState.Index' (duration: 347.252373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:37.787037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103067568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-14T07:10:37.787056Z","caller":"traceutil/trace.go:171","msg":"trace[904433638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25948; }","duration":"1.103130311s","start":"2026-04-14T07:10:36.68392Z","end":"2026-04-14T07:10:37.787051Z","steps":["trace[904433638] 'agreement among raft nodes before linearized reading' (duration: 1.103041058s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.787072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.683898Z","time spent":"1.103171112s","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T07:10:37.787107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095898112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-04-14T07:10:37.787411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286240905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-04-14T07:10:37.787429Z","caller":"traceutil/trace.go:171","msg":"trace[1539606916] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:25948; }","duration":"1.286285007s","start":"2026-04-14T07:10:36.501139Z","end":"2026-04-14T07:10:37.787424Z","steps":["trace[1539606916] 'agreement among raft nodes before linearized reading' (duration: 1.286227205s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.787442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.501122Z","time spent":"1.286317207s","remote":"127.0.0.1:38796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3589,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-04-14T07:10:37.787431Z","caller":"traceutil/trace.go:171","msg":"trace[808290244] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25948; }","duration":"1.096255592s","start":"2026-04-14T07:10:36.691136Z","end":"2026-04-14T07:10:37.787391Z","steps":["trace[808290244] 'agreement among raft nodes before linearized reading' (duration: 1.095788348s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.787441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028854996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T07:10:37.787416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245970924s","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-04-14T07:10:37.787422Z","caller":"traceutil/trace.go:171","msg":"trace[426244955] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"793.241745ms","start":"2026-04-14T07:10:36.994128Z","end":"2026-04-14T07:10:37.78737Z","steps":["trace[426244955] 'process raft request' (duration: 792.35396ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:37.787477Z","caller":"traceutil/trace.go:171","msg":"trace[1324238847] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25948; }","duration":"1.028931108s","start":"2026-04-14T07:10:36.758537Z","end":"2026-04-14T07:10:37.787468Z","steps":["trace[1324238847] 'agreement among raft nodes before linearized reading' (duration: 1.028863666s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.787487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.691114Z","time spent":"1.096357725s","remote":"127.0.0.1:49640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-14T07:10:37.787512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.758514Z","time spent":"1.02898753s","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:10:37.787503Z","caller":"traceutil/trace.go:171","msg":"trace[584012472] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25948; }","duration":"1.246093697s","start":"2026-04-14T07:10:36.541388Z","end":"2026-04-14T07:10:37.787482Z","steps":["trace[584012472] 'agreement among raft nodes before linearized reading' (duration: 1.245913172s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.787518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108452804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-14T07:10:37.787553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.54137Z","time spent":"1.246174159s","remote":"127.0.0.1:39172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-14T07:10:37.787569Z","caller":"traceutil/trace.go:171","msg":"trace[795935692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25948; }","duration":"1.108540926s","start":"2026-04-14T07:10:36.679013Z","end":"2026-04-14T07:10:37.787554Z","steps":["trace[795935692] 'agreement among raft nodes before linearized reading' (duration: 1.108372492s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.787343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216698128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T07:10:37.787594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.994089Z","time spent":"793.42004ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:37.787609Z","caller":"traceutil/trace.go:171","msg":"trace[945657840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25948; }","duration":"1.216973706s","start":"2026-04-14T07:10:36.57063Z","end":"2026-04-14T07:10:37.787604Z","steps":["trace[945657840] 'agreement among raft nodes before linearized reading' (duration: 1.216659477s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.787612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.678992Z","time spent":"1.108609079s","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T07:10:37.787628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.570559Z","time spent":"1.217064368s","remote":"127.0.0.1:38728","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-04-14T07:10:37.787601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.922148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-14T07:10:37.78757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.994666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-14T07:10:37.787953Z","caller":"traceutil/trace.go:171","msg":"trace[1800300903] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8; range_end:; response_count:1; response_revision:25948; }","duration":"778.48162ms","start":"2026-04-14T07:10:37.009435Z","end":"2026-04-14T07:10:37.787916Z","steps":["trace[1800300903] 'agreement among raft nodes before linearized reading' (duration: 777.669687ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:37.787699Z","caller":"traceutil/trace.go:171","msg":"trace[1721631019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25948; }","duration":"959.059752ms","start":"2026-04-14T07:10:36.828617Z","end":"2026-04-14T07:10:37.787677Z","steps":["trace[1721631019] 'agreement among raft nodes before linearized reading' (duration: 958.767204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:37.788035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.828596Z","time spent":"959.425102ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T07:10:37.78805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.009407Z","time spent":"778.622234ms","remote":"127.0.0.1:38886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8\" "} {"level":"warn","ts":"2026-04-14T07:10:38.513566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.774421ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T07:10:38.51513Z","caller":"traceutil/trace.go:171","msg":"trace[1955408100] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"985.790704ms","start":"2026-04-14T07:10:37.529311Z","end":"2026-04-14T07:10:38.515102Z","steps":["trace[1955408100] 'process raft request' (duration: 984.364482ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:38.515148Z","caller":"traceutil/trace.go:171","msg":"trace[519752057] linearizableReadLoop","detail":"{readStateIndex:26800; appliedIndex:26798; }","duration":"728.176219ms","start":"2026-04-14T07:10:37.786944Z","end":"2026-04-14T07:10:38.51512Z","steps":["trace[519752057] 'read index received' (duration: 19.882704ms)","trace[519752057] 'applied index is now lower than readState.Index' (duration: 708.291845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:38.515493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.529294Z","time spent":"985.900898ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:38.812159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427123823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-04-14T07:10:38.812221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716134385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T07:10:38.812239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696858658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18a627977fd94201\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-04-14T07:10:38.812321Z","caller":"traceutil/trace.go:171","msg":"trace[60125129] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25949; }","duration":"1.716253138s","start":"2026-04-14T07:10:37.096044Z","end":"2026-04-14T07:10:38.812297Z","steps":["trace[60125129] 'agreement among raft nodes before linearized reading' (duration: 1.419249706s)","trace[60125129] 'range keys from in-memory index tree' (duration: 296.847408ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:38.812366Z","caller":"traceutil/trace.go:171","msg":"trace[1264801940] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18a627977fd94201; range_end:; response_count:1; response_revision:25949; }","duration":"1.697023573s","start":"2026-04-14T07:10:37.115309Z","end":"2026-04-14T07:10:38.812332Z","steps":["trace[1264801940] 'agreement among raft nodes before linearized reading' (duration: 1.399971489s)","trace[1264801940] 'range keys from in-memory index tree' (duration: 296.763295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:38.812372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.095994Z","time spent":"1.716367352s","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-04-14T07:10:38.812253Z","caller":"traceutil/trace.go:171","msg":"trace[1177781120] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:25949; }","duration":"1.427261367s","start":"2026-04-14T07:10:37.384969Z","end":"2026-04-14T07:10:38.812231Z","steps":["trace[1177781120] 'agreement among raft nodes before linearized reading' (duration: 1.130260345s)","trace[1177781120] 'count revisions from in-memory index tree' (duration: 296.852588ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:38.81244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.115293Z","time spent":"1.697125976s","remote":"127.0.0.1:38784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1033,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18a627977fd94201\" "} {"level":"warn","ts":"2026-04-14T07:10:38.81249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.303101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-04-14T07:10:38.812505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.38495Z","time spent":"1.427518775s","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":135,"response size":32,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:10:38.812703Z","caller":"traceutil/trace.go:171","msg":"trace[1635692481] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"1.020553247s","start":"2026-04-14T07:10:37.792132Z","end":"2026-04-14T07:10:38.812685Z","steps":["trace[1635692481] 'process raft request' (duration: 722.976288ms)","trace[1635692481] 'compare' (duration: 297.124926ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:38.812761Z","caller":"traceutil/trace.go:171","msg":"trace[1848191628] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"1.019393974s","start":"2026-04-14T07:10:37.793351Z","end":"2026-04-14T07:10:38.812745Z","steps":["trace[1848191628] 'process raft request' (duration: 1.019326432s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.81284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.792115Z","time spent":"1.02062476s","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:38.812853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.793343Z","time spent":"1.019478336s","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:38.812914Z","caller":"traceutil/trace.go:171","msg":"trace[1650309118] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"1.020739402s","start":"2026-04-14T07:10:37.792161Z","end":"2026-04-14T07:10:38.8129Z","steps":["trace[1650309118] 'process raft request' (duration: 1.020411133s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.813016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.792137Z","time spent":"1.020814065s","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:38.931041Z","caller":"traceutil/trace.go:171","msg":"trace[420737483] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"938.810658ms","start":"2026-04-14T07:10:37.992192Z","end":"2026-04-14T07:10:38.931002Z","steps":["trace[420737483] 'process raft request' (duration: 938.662304ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:38.93099Z","caller":"traceutil/trace.go:171","msg":"trace[1204465554] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"1.019787205s","start":"2026-04-14T07:10:37.911162Z","end":"2026-04-14T07:10:38.93095Z","steps":["trace[1204465554] 'process raft request' (duration: 1.015988836s)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:38.931098Z","caller":"traceutil/trace.go:171","msg":"trace[538225851] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"923.436873ms","start":"2026-04-14T07:10:38.007586Z","end":"2026-04-14T07:10:38.931022Z","steps":["trace[538225851] 'process raft request' (duration: 923.360991ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:38.931261Z","caller":"traceutil/trace.go:171","msg":"trace[2086465173] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"923.521766ms","start":"2026-04-14T07:10:38.007723Z","end":"2026-04-14T07:10:38.931245Z","steps":["trace[2086465173] 'process raft request' (duration: 923.265489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.93127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.992161Z","time spent":"939.037054ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:38.931266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.911134Z","time spent":"1.020020161s","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:38.931195Z","caller":"traceutil/trace.go:171","msg":"trace[272112530] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"923.623188ms","start":"2026-04-14T07:10:38.007537Z","end":"2026-04-14T07:10:38.93116Z","steps":["trace[272112530] 'process raft request' (duration: 923.370871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.931391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:38.007693Z","time spent":"923.609858ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":553,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:38.931409Z","caller":"traceutil/trace.go:171","msg":"trace[1759187721] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"923.354371ms","start":"2026-04-14T07:10:38.008039Z","end":"2026-04-14T07:10:38.931393Z","steps":["trace[1759187721] 'process raft request' (duration: 923.123294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.931403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:38.007521Z","time spent":"923.831845ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:38.931394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:38.007549Z","time spent":"923.726112ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:38.931538Z","caller":"traceutil/trace.go:171","msg":"trace[1501039337] linearizableReadLoop","detail":"{readStateIndex:26809; appliedIndex:26803; }","duration":"416.271614ms","start":"2026-04-14T07:10:38.515221Z","end":"2026-04-14T07:10:38.931493Z","steps":["trace[1501039337] 'read index received' (duration: 411.885238ms)","trace[1501039337] 'applied index is now lower than readState.Index' (duration: 4.380076ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:38.93171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.419474ms","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-04-14T07:10:38.931776Z","caller":"traceutil/trace.go:171","msg":"trace[1040664943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25959; }","duration":"456.552698ms","start":"2026-04-14T07:10:38.47521Z","end":"2026-04-14T07:10:38.931762Z","steps":["trace[1040664943] 'agreement among raft nodes before linearized reading' (duration: 456.338161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.931797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140126748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:38.931795Z","caller":"traceutil/trace.go:171","msg":"trace[630536147] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"253.487407ms","start":"2026-04-14T07:10:38.678282Z","end":"2026-04-14T07:10:38.931769Z","steps":["trace[630536147] 'process raft request' (duration: 253.101285ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:38.931842Z","caller":"traceutil/trace.go:171","msg":"trace[485995570] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25960; }","duration":"1.140203841s","start":"2026-04-14T07:10:37.791627Z","end":"2026-04-14T07:10:38.931831Z","steps":["trace[485995570] 'agreement among raft nodes before linearized reading' (duration: 1.140109628s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.931854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.700617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T07:10:38.931878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.791608Z","time spent":"1.140263403s","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-14T07:10:38.931717Z","caller":"traceutil/trace.go:171","msg":"trace[410835036] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"116.913645ms","start":"2026-04-14T07:10:38.814781Z","end":"2026-04-14T07:10:38.931694Z","steps":["trace[410835036] 'process raft request' (duration: 116.74038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.932003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.367784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-14T07:10:38.932028Z","caller":"traceutil/trace.go:171","msg":"trace[392111513] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8; range_end:; response_count:1; response_revision:25960; }","duration":"138.438027ms","start":"2026-04-14T07:10:38.793585Z","end":"2026-04-14T07:10:38.932023Z","steps":["trace[392111513] 'agreement among raft nodes before linearized reading' (duration: 138.335544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.932006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143202717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:38.932095Z","caller":"traceutil/trace.go:171","msg":"trace[1976451557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25960; }","duration":"1.143316741s","start":"2026-04-14T07:10:37.788767Z","end":"2026-04-14T07:10:38.932083Z","steps":["trace[1976451557] 'agreement among raft nodes before linearized reading' (duration: 1.143203707s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.931567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:38.008029Z","time spent":"923.465424ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:38.932172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.788747Z","time spent":"1.143390023s","remote":"127.0.0.1:38724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T07:10:38.931896Z","caller":"traceutil/trace.go:171","msg":"trace[655507654] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25960; }","duration":"214.80753ms","start":"2026-04-14T07:10:38.717081Z","end":"2026-04-14T07:10:38.931888Z","steps":["trace[655507654] 'agreement among raft nodes before linearized reading' (duration: 214.732318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.931686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137967047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-04-14T07:10:38.932653Z","caller":"traceutil/trace.go:171","msg":"trace[1042157901] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25959; }","duration":"1.138948855s","start":"2026-04-14T07:10:37.793688Z","end":"2026-04-14T07:10:38.932637Z","steps":["trace[1042157901] 'agreement among raft nodes before linearized reading' (duration: 1.137807262s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.932712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.793664Z","time spent":"1.139031837s","remote":"127.0.0.1:38796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1335,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-04-14T07:10:38.931914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139051118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-04-14T07:10:38.933015Z","caller":"traceutil/trace.go:171","msg":"trace[2051600198] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:25960; }","duration":"1.14018394s","start":"2026-04-14T07:10:37.7928Z","end":"2026-04-14T07:10:38.932984Z","steps":["trace[2051600198] 'agreement among raft nodes before linearized reading' (duration: 1.138878753s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.933065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:37.792786Z","time spent":"1.140263772s","remote":"127.0.0.1:38796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3589,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-04-14T07:10:38.931861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:38.475188Z","time spent":"456.63301ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T07:10:41.910848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.656608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-14T07:10:41.910944Z","caller":"traceutil/trace.go:171","msg":"trace[1945135000] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25989; }","duration":"388.811643ms","start":"2026-04-14T07:10:41.522116Z","end":"2026-04-14T07:10:41.910928Z","steps":["trace[1945135000] 'range keys from in-memory index tree' (duration: 388.486834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:41.910983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:41.5221Z","time spent":"388.872774ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-14T07:10:41.965561Z","caller":"traceutil/trace.go:171","msg":"trace[1324601079] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"164.176248ms","start":"2026-04-14T07:10:41.801358Z","end":"2026-04-14T07:10:41.965535Z","steps":["trace[1324601079] 'process raft request' (duration: 163.997032ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:42.081281Z","caller":"traceutil/trace.go:171","msg":"trace[632465228] transaction","detail":"{read_only:false; response_revision:25991; number_of_response:1; }","duration":"276.899092ms","start":"2026-04-14T07:10:41.80434Z","end":"2026-04-14T07:10:42.081239Z","steps":["trace[632465228] 'process raft request' (duration: 274.699258ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:42.081561Z","caller":"traceutil/trace.go:171","msg":"trace[7849384] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"272.739132ms","start":"2026-04-14T07:10:41.808792Z","end":"2026-04-14T07:10:42.081531Z","steps":["trace[7849384] 'process raft request' (duration: 272.355431ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:42.081792Z","caller":"traceutil/trace.go:171","msg":"trace[1847050598] linearizableReadLoop","detail":"{readStateIndex:26844; appliedIndex:26840; }","duration":"133.912065ms","start":"2026-04-14T07:10:41.947865Z","end":"2026-04-14T07:10:42.081777Z","steps":["trace[1847050598] 'read index received' (duration: 17.515825ms)","trace[1847050598] 'applied index is now lower than readState.Index' (duration: 116.39492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:42.08206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.156862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8\" ","response":"range_response_count:1 size:3937"} {"level":"info","ts":"2026-04-14T07:10:42.082132Z","caller":"traceutil/trace.go:171","msg":"trace[951133851] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8; range_end:; response_count:1; response_revision:25993; }","duration":"134.285076ms","start":"2026-04-14T07:10:41.947826Z","end":"2026-04-14T07:10:42.082111Z","steps":["trace[951133851] 'agreement among raft nodes before linearized reading' (duration: 134.007228ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:42.083019Z","caller":"traceutil/trace.go:171","msg":"trace[1809019792] transaction","detail":"{read_only:false; response_revision:25993; number_of_response:1; }","duration":"167.460282ms","start":"2026-04-14T07:10:41.914996Z","end":"2026-04-14T07:10:42.082456Z","steps":["trace[1809019792] 'process raft request' (duration: 166.414733ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:47.109817Z","caller":"traceutil/trace.go:171","msg":"trace[932496844] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"148.63984ms","start":"2026-04-14T07:10:46.961153Z","end":"2026-04-14T07:10:47.109793Z","steps":["trace[932496844] 'process raft request' (duration: 148.483585ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:47.119151Z","caller":"traceutil/trace.go:171","msg":"trace[593188137] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"153.226912ms","start":"2026-04-14T07:10:46.965902Z","end":"2026-04-14T07:10:47.119129Z","steps":["trace[593188137] 'process raft request' (duration: 153.076627ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:47.119396Z","caller":"traceutil/trace.go:171","msg":"trace[78037765] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"142.607256ms","start":"2026-04-14T07:10:46.976756Z","end":"2026-04-14T07:10:47.119363Z","steps":["trace[78037765] 'process raft request' (duration: 142.330308ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:47.119455Z","caller":"traceutil/trace.go:171","msg":"trace[67271585] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"140.981059ms","start":"2026-04-14T07:10:46.978458Z","end":"2026-04-14T07:10:47.119439Z","steps":["trace[67271585] 'process raft request' (duration: 140.775583ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:47.119538Z","caller":"traceutil/trace.go:171","msg":"trace[1228257981] linearizableReadLoop","detail":"{readStateIndex:26907; appliedIndex:26902; }","duration":"118.101329ms","start":"2026-04-14T07:10:47.001391Z","end":"2026-04-14T07:10:47.119492Z","steps":["trace[1228257981] 'read index received' (duration: 108.219035ms)","trace[1228257981] 'applied index is now lower than readState.Index' (duration: 9.881114ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:47.119634Z","caller":"traceutil/trace.go:171","msg":"trace[1381013598] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"139.014072ms","start":"2026-04-14T07:10:46.9806Z","end":"2026-04-14T07:10:47.119614Z","steps":["trace[1381013598] 'process raft request' (duration: 138.784325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:47.119846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.447918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-14T07:10:47.119888Z","caller":"traceutil/trace.go:171","msg":"trace[119741818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26054; }","duration":"118.599963ms","start":"2026-04-14T07:10:47.001278Z","end":"2026-04-14T07:10:47.119878Z","steps":["trace[119741818] 'agreement among raft nodes before linearized reading' (duration: 118.298364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:47.404797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.972797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:47.404908Z","caller":"traceutil/trace.go:171","msg":"trace[1250727408] linearizableReadLoop","detail":"{readStateIndex:26908; appliedIndex:26907; }","duration":"271.566798ms","start":"2026-04-14T07:10:47.133324Z","end":"2026-04-14T07:10:47.404891Z","steps":["trace[1250727408] 'read index received' (duration: 111.458657ms)","trace[1250727408] 'applied index is now lower than readState.Index' (duration: 160.106621ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:47.405Z","caller":"traceutil/trace.go:171","msg":"trace[195634129] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"281.178815ms","start":"2026-04-14T07:10:47.123797Z","end":"2026-04-14T07:10:47.404976Z","steps":["trace[195634129] 'process raft request' (duration: 120.948951ms)","trace[195634129] 'compare' (duration: 159.7368ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:47.405099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.770084ms","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-04-14T07:10:47.405128Z","caller":"traceutil/trace.go:171","msg":"trace[1666155141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26055; }","duration":"271.814565ms","start":"2026-04-14T07:10:47.133303Z","end":"2026-04-14T07:10:47.405118Z","steps":["trace[1666155141] 'agreement among raft nodes before linearized reading' (duration: 271.680252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:47.405156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.518301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:10:47.405238Z","caller":"traceutil/trace.go:171","msg":"trace[1811122217] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:26055; }","duration":"153.644095ms","start":"2026-04-14T07:10:47.251573Z","end":"2026-04-14T07:10:47.405217Z","steps":["trace[1811122217] 'agreement among raft nodes before linearized reading' (duration: 153.47654ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:47.571236Z","caller":"traceutil/trace.go:171","msg":"trace[1277887351] transaction","detail":"{read_only:false; response_revision:26056; number_of_response:1; }","duration":"161.590055ms","start":"2026-04-14T07:10:47.409612Z","end":"2026-04-14T07:10:47.571202Z","steps":["trace[1277887351] 'process raft request' (duration: 159.821814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:47.893827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.401873ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T07:11:05.369032Z","caller":"traceutil/trace.go:171","msg":"trace[2078706478] linearizableReadLoop","detail":"{readStateIndex:27049; appliedIndex:27048; }","duration":"407.424771ms","start":"2026-04-14T07:11:04.961585Z","end":"2026-04-14T07:11:05.36901Z","steps":["trace[2078706478] 'read index received' (duration: 407.249576ms)","trace[2078706478] 'applied index is now lower than readState.Index' (duration: 174.045µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:11:05.369135Z","caller":"traceutil/trace.go:171","msg":"trace[1885497895] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"435.941364ms","start":"2026-04-14T07:11:04.933181Z","end":"2026-04-14T07:11:05.369122Z","steps":["trace[1885497895] 'process raft request' (duration: 435.675656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.369234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:04.933158Z","time spent":"436.004275ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:11:05.369387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.779381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:11:05.369472Z","caller":"traceutil/trace.go:171","msg":"trace[1396773664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26192; }","duration":"407.899304ms","start":"2026-04-14T07:11:04.961552Z","end":"2026-04-14T07:11:05.369451Z","steps":["trace[1396773664] 'agreement among raft nodes before linearized reading' (duration: 407.681878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.36952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:04.961534Z","time spent":"407.975506ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T07:11:05.469513Z","caller":"traceutil/trace.go:171","msg":"trace[1690306213] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"308.773243ms","start":"2026-04-14T07:11:05.160714Z","end":"2026-04-14T07:11:05.469487Z","steps":["trace[1690306213] 'process raft request' (duration: 234.336064ms)","trace[1690306213] 'compare' (duration: 74.093199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:11:05.46966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.160694Z","time spent":"308.889637ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":553,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:11:05.469751Z","caller":"traceutil/trace.go:171","msg":"trace[601281755] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"291.271067ms","start":"2026-04-14T07:11:05.178464Z","end":"2026-04-14T07:11:05.469735Z","steps":["trace[601281755] 'process raft request' (duration: 290.977919ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:05.470008Z","caller":"traceutil/trace.go:171","msg":"trace[1601298176] transaction","detail":"{read_only:false; response_revision:26195; number_of_response:1; }","duration":"290.098614ms","start":"2026-04-14T07:11:05.179902Z","end":"2026-04-14T07:11:05.47Z","steps":["trace[1601298176] 'process raft request' (duration: 289.765314ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:05.470137Z","caller":"traceutil/trace.go:171","msg":"trace[1445997317] linearizableReadLoop","detail":"{readStateIndex:27054; appliedIndex:27049; }","duration":"101.023416ms","start":"2026-04-14T07:11:05.369091Z","end":"2026-04-14T07:11:05.470114Z","steps":["trace[1445997317] 'read index received' (duration: 26.041741ms)","trace[1445997317] 'applied index is now lower than readState.Index' (duration: 74.980605ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:11:05.470222Z","caller":"traceutil/trace.go:171","msg":"trace[1859937061] transaction","detail":"{read_only:false; response_revision:26196; number_of_response:1; }","duration":"288.711914ms","start":"2026-04-14T07:11:05.181503Z","end":"2026-04-14T07:11:05.470215Z","steps":["trace[1859937061] 'process raft request' (duration: 288.373064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.470243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.834285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:11:05.47027Z","caller":"traceutil/trace.go:171","msg":"trace[255481700] transaction","detail":"{read_only:false; response_revision:26197; number_of_response:1; }","duration":"286.948603ms","start":"2026-04-14T07:11:05.183317Z","end":"2026-04-14T07:11:05.470266Z","steps":["trace[255481700] 'process raft request' (duration: 286.722467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.470272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.222901ms","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:6"} {"level":"info","ts":"2026-04-14T07:11:05.470299Z","caller":"traceutil/trace.go:171","msg":"trace[1260702553] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:26197; }","duration":"210.908438ms","start":"2026-04-14T07:11:05.259374Z","end":"2026-04-14T07:11:05.470282Z","steps":["trace[1260702553] 'agreement among raft nodes before linearized reading' (duration: 210.815405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.470446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.443242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10851"} {"level":"info","ts":"2026-04-14T07:11:05.470468Z","caller":"traceutil/trace.go:171","msg":"trace[1231519372] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26197; }","duration":"450.486533ms","start":"2026-04-14T07:11:05.019977Z","end":"2026-04-14T07:11:05.470463Z","steps":["trace[1231519372] 'agreement among raft nodes before linearized reading' (duration: 450.366879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.470484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.019972Z","time spent":"450.507883ms","remote":"127.0.0.1:38886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10874,"request content":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-04-14T07:11:05.470307Z","caller":"traceutil/trace.go:171","msg":"trace[614780634] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:26197; }","duration":"243.318173ms","start":"2026-04-14T07:11:05.226977Z","end":"2026-04-14T07:11:05.470296Z","steps":["trace[614780634] 'agreement among raft nodes before linearized reading' (duration: 243.241881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.470637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.652175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-14T07:11:05.470681Z","caller":"traceutil/trace.go:171","msg":"trace[1183931585] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26197; }","duration":"434.730968ms","start":"2026-04-14T07:11:05.035939Z","end":"2026-04-14T07:11:05.47067Z","steps":["trace[1183931585] 'agreement among raft nodes before linearized reading' (duration: 434.389058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.470716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.03592Z","time spent":"434.78752ms","remote":"127.0.0.1:38854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-04-14T07:11:05.47074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.558401ms","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-04-14T07:11:05.470762Z","caller":"traceutil/trace.go:171","msg":"trace[15179282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26197; }","duration":"453.600693ms","start":"2026-04-14T07:11:05.017153Z","end":"2026-04-14T07:11:05.470753Z","steps":["trace[15179282] 'agreement among raft nodes before linearized reading' (duration: 453.539161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.470775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.017139Z","time spent":"453.632644ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T07:11:05.720325Z","caller":"traceutil/trace.go:171","msg":"trace[650310720] linearizableReadLoop","detail":"{readStateIndex:27055; appliedIndex:27054; }","duration":"250.115249ms","start":"2026-04-14T07:11:05.470194Z","end":"2026-04-14T07:11:05.720309Z","steps":["trace[650310720] 'read index received' (duration: 184.29649ms)","trace[650310720] 'applied index is now lower than readState.Index' (duration: 65.818069ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:11:05.720399Z","caller":"traceutil/trace.go:171","msg":"trace[1905543997] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"347.324296ms","start":"2026-04-14T07:11:05.373067Z","end":"2026-04-14T07:11:05.720391Z","steps":["trace[1905543997] 'process raft request' (duration: 281.423204ms)","trace[1905543997] 'compare' (duration: 65.748317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:11:05.720463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.373048Z","time spent":"347.364726ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:11:05.72057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.705173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:11:05.720654Z","caller":"traceutil/trace.go:171","msg":"trace[748638483] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:26198; }","duration":"340.847337ms","start":"2026-04-14T07:11:05.379787Z","end":"2026-04-14T07:11:05.720634Z","steps":["trace[748638483] 'agreement among raft nodes before linearized reading' (duration: 340.698703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:05.720695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.37977Z","time spent":"340.91571ms","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:11:06.059622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.340229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:11:06.059777Z","caller":"traceutil/trace.go:171","msg":"trace[1721583755] linearizableReadLoop","detail":"{readStateIndex:27057; appliedIndex:27055; }","duration":"339.396546ms","start":"2026-04-14T07:11:05.72037Z","end":"2026-04-14T07:11:06.059767Z","steps":["trace[1721583755] 'read index received' (duration: 118.845731ms)","trace[1721583755] 'applied index is now lower than readState.Index' (duration: 220.550045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:11:06.059877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.896917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:11:06.059891Z","caller":"traceutil/trace.go:171","msg":"trace[957218433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26200; }","duration":"491.949609ms","start":"2026-04-14T07:11:05.567935Z","end":"2026-04-14T07:11:06.059885Z","steps":["trace[957218433] 'agreement among raft nodes before linearized reading' (duration: 491.898668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.059908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.567913Z","time spent":"491.99156ms","remote":"127.0.0.1:38728","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-04-14T07:11:06.060041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.466097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:11:06.060054Z","caller":"traceutil/trace.go:171","msg":"trace[750555480] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:26200; }","duration":"552.481637ms","start":"2026-04-14T07:11:05.507568Z","end":"2026-04-14T07:11:06.06005Z","steps":["trace[750555480] 'agreement among raft nodes before linearized reading' (duration: 552.452406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.060066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.507553Z","time spent":"552.510777ms","remote":"127.0.0.1:39092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:11:06.060093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.594552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T07:11:06.060186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.902397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-14T07:11:06.060205Z","caller":"traceutil/trace.go:171","msg":"trace[252772923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26200; }","duration":"572.952878ms","start":"2026-04-14T07:11:05.487248Z","end":"2026-04-14T07:11:06.0602Z","steps":["trace[252772923] 'agreement among raft nodes before linearized reading' (duration: 572.888305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.060218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.487225Z","time spent":"572.989939ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T07:11:06.060209Z","caller":"traceutil/trace.go:171","msg":"trace[108807011] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26200; }","duration":"488.83993ms","start":"2026-04-14T07:11:05.571344Z","end":"2026-04-14T07:11:06.060184Z","steps":["trace[108807011] 'agreement among raft nodes before linearized reading' (duration: 488.484539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.060284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.073332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-04-14T07:11:06.060275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.571296Z","time spent":"488.961874ms","remote":"127.0.0.1:43042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:11:06.060302Z","caller":"traceutil/trace.go:171","msg":"trace[332260309] range","detail":"{range_begin:/registry/persistentvolumes/pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8; range_end:; response_count:1; response_revision:26200; }","duration":"573.123473ms","start":"2026-04-14T07:11:05.487172Z","end":"2026-04-14T07:11:06.060295Z","steps":["trace[332260309] 'agreement among raft nodes before linearized reading' (duration: 573.076482ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:06.060298Z","caller":"traceutil/trace.go:171","msg":"trace[1089451480] transaction","detail":"{read_only:false; response_revision:26199; number_of_response:1; }","duration":"586.478868ms","start":"2026-04-14T07:11:05.473795Z","end":"2026-04-14T07:11:06.060274Z","steps":["trace[1089451480] 'process raft request' (duration: 365.399617ms)","trace[1089451480] 'compare' (duration: 220.224056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:11:06.060361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.473778Z","time spent":"586.558681ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:11:06.060359Z","caller":"traceutil/trace.go:171","msg":"trace[1103244338] transaction","detail":"{read_only:false; response_revision:26200; number_of_response:1; }","duration":"572.979348ms","start":"2026-04-14T07:11:05.487331Z","end":"2026-04-14T07:11:06.06031Z","steps":["trace[1103244338] 'process raft request' (duration: 572.38288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.060316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.48714Z","time spent":"573.172814ms","remote":"127.0.0.1:38844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-107c5c87-c00e-48c4-9442-5dd45c2bb3a8\" "} {"level":"warn","ts":"2026-04-14T07:11:06.060506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.487284Z","time spent":"573.137264ms","remote":"127.0.0.1:38886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10653,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:11:06.060492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.790745ms","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":"warn","ts":"2026-04-14T07:11:06.060582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.938414ms","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-04-14T07:11:06.060573Z","caller":"traceutil/trace.go:171","msg":"trace[115642364] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26200; }","duration":"253.931509ms","start":"2026-04-14T07:11:05.806622Z","end":"2026-04-14T07:11:06.060553Z","steps":["trace[115642364] 'agreement among raft nodes before linearized reading' (duration: 253.708353ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:06.060604Z","caller":"traceutil/trace.go:171","msg":"trace[1758470171] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26200; }","duration":"241.980476ms","start":"2026-04-14T07:11:05.818617Z","end":"2026-04-14T07:11:06.060598Z","steps":["trace[1758470171] 'agreement among raft nodes before linearized reading' (duration: 241.943045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.060651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.733541ms","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-04-14T07:11:06.060699Z","caller":"traceutil/trace.go:171","msg":"trace[1070054766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26200; }","duration":"414.846044ms","start":"2026-04-14T07:11:05.64584Z","end":"2026-04-14T07:11:06.060686Z","steps":["trace[1070054766] 'agreement among raft nodes before linearized reading' (duration: 414.669449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.060737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:05.645805Z","time spent":"414.922316ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T07:11:06.30523Z","caller":"traceutil/trace.go:171","msg":"trace[1844616203] transaction","detail":"{read_only:false; response_revision:26201; number_of_response:1; }","duration":"239.05291ms","start":"2026-04-14T07:11:06.066131Z","end":"2026-04-14T07:11:06.305184Z","steps":["trace[1844616203] 'process raft request' (duration: 238.884635ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:06.375325Z","caller":"traceutil/trace.go:171","msg":"trace[1892049925] transaction","detail":"{read_only:false; response_revision:26202; number_of_response:1; }","duration":"309.07355ms","start":"2026-04-14T07:11:06.066217Z","end":"2026-04-14T07:11:06.375291Z","steps":["trace[1892049925] 'process raft request' (duration: 308.842754ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:06.375433Z","caller":"traceutil/trace.go:171","msg":"trace[2115710854] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"307.549597ms","start":"2026-04-14T07:11:06.067853Z","end":"2026-04-14T07:11:06.375403Z","steps":["trace[2115710854] 'process raft request' (duration: 307.437093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.375481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:06.066197Z","time spent":"309.201644ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:11:06.375592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:06.067842Z","time spent":"307.669601ms","remote":"127.0.0.1:38866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":884,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:11:06.375659Z","caller":"traceutil/trace.go:171","msg":"trace[740670179] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"307.803014ms","start":"2026-04-14T07:11:06.067828Z","end":"2026-04-14T07:11:06.375631Z","steps":["trace[740670179] 'process raft request' (duration: 307.422123ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:06.375683Z","caller":"traceutil/trace.go:171","msg":"trace[66080927] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"308.0129ms","start":"2026-04-14T07:11:06.06763Z","end":"2026-04-14T07:11:06.375642Z","steps":["trace[66080927] 'process raft request' (duration: 307.566318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.375786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:06.067821Z","time spent":"307.896337ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:11:06.375788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:06.067615Z","time spent":"308.118843ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:11:06.375873Z","caller":"traceutil/trace.go:171","msg":"trace[325567165] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"303.961263ms","start":"2026-04-14T07:11:06.071862Z","end":"2026-04-14T07:11:06.375823Z","steps":["trace[325567165] 'process raft request' (duration: 303.489979ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:06.37594Z","caller":"traceutil/trace.go:171","msg":"trace[330863934] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"298.64919ms","start":"2026-04-14T07:11:06.077256Z","end":"2026-04-14T07:11:06.375906Z","steps":["trace[330863934] 'process raft request' (duration: 298.427534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:06.376085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:11:06.071852Z","time spent":"304.123488ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:11:06.376136Z","caller":"traceutil/trace.go:171","msg":"trace[412890244] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"191.009173ms","start":"2026-04-14T07:11:06.185101Z","end":"2026-04-14T07:11:06.37611Z","steps":["trace[412890244] 'process raft request' (duration: 190.721555ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:06.376177Z","caller":"traceutil/trace.go:171","msg":"trace[1645938024] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"189.452847ms","start":"2026-04-14T07:11:06.186709Z","end":"2026-04-14T07:11:06.376162Z","steps":["trace[1645938024] 'process raft request' (duration: 189.302773ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:06.37611Z","caller":"traceutil/trace.go:171","msg":"trace[894917984] linearizableReadLoop","detail":"{readStateIndex:27066; appliedIndex:27057; }","duration":"178.286255ms","start":"2026-04-14T07:11:06.197802Z","end":"2026-04-14T07:11:06.376088Z","steps":["trace[894917984] 'read index received' (duration: 107.249915ms)","trace[894917984] 'applied index is now lower than readState.Index' (duration: 71.03546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:11:06.376417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.593814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7749"} {"level":"info","ts":"2026-04-14T07:11:06.376463Z","caller":"traceutil/trace.go:171","msg":"trace[2101939481] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26209; }","duration":"178.673317ms","start":"2026-04-14T07:11:06.197776Z","end":"2026-04-14T07:11:06.37645Z","steps":["trace[2101939481] 'agreement among raft nodes before linearized reading' (duration: 178.488751ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:09.985774Z","caller":"traceutil/trace.go:171","msg":"trace[1650025690] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"120.634042ms","start":"2026-04-14T07:11:09.865107Z","end":"2026-04-14T07:11:09.985741Z","steps":["trace[1650025690] 'process raft request' (duration: 40.346254ms)","trace[1650025690] 'compare' (duration: 80.165744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:11:10.239295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.590555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:11:10.239452Z","caller":"traceutil/trace.go:171","msg":"trace[499292667] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"161.363558ms","start":"2026-04-14T07:11:10.078061Z","end":"2026-04-14T07:11:10.239425Z","steps":["trace[499292667] 'process raft request' (duration: 27.491164ms)","trace[499292667] 'compare' (duration: 133.292037ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:11:10.435316Z","caller":"traceutil/trace.go:171","msg":"trace[205539930] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"111.688054ms","start":"2026-04-14T07:11:10.323599Z","end":"2026-04-14T07:11:10.435287Z","steps":["trace[205539930] 'process raft request' (duration: 85.124248ms)","trace[205539930] 'compare' (duration: 25.625509ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:11:10.55918Z","caller":"traceutil/trace.go:171","msg":"trace[326511535] linearizableReadLoop","detail":"{readStateIndex:27101; appliedIndex:27100; }","duration":"116.123701ms","start":"2026-04-14T07:11:10.443026Z","end":"2026-04-14T07:11:10.559149Z","steps":["trace[326511535] 'read index received' (duration: 103.547218ms)","trace[326511535] 'applied index is now lower than readState.Index' (duration: 12.570723ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:11:10.559237Z","caller":"traceutil/trace.go:171","msg":"trace[1489121795] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"118.676066ms","start":"2026-04-14T07:11:10.440523Z","end":"2026-04-14T07:11:10.559199Z","steps":["trace[1489121795] 'process raft request' (duration: 105.99875ms)","trace[1489121795] 'compare' (duration: 12.441949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:11:10.559394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.345209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-04-14T07:11:10.55944Z","caller":"traceutil/trace.go:171","msg":"trace[203921157] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26243; }","duration":"116.428341ms","start":"2026-04-14T07:11:10.442998Z","end":"2026-04-14T07:11:10.559427Z","steps":["trace[203921157] 'agreement among raft nodes before linearized reading' (duration: 116.256816ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:10.976381Z","caller":"traceutil/trace.go:171","msg":"trace[53989404] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"151.377289ms","start":"2026-04-14T07:11:10.824982Z","end":"2026-04-14T07:11:10.976359Z","steps":["trace[53989404] 'process raft request' (duration: 151.166883ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:11.602083Z","caller":"traceutil/trace.go:171","msg":"trace[673511195] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"106.129333ms","start":"2026-04-14T07:11:11.49592Z","end":"2026-04-14T07:11:11.60205Z","steps":["trace[673511195] 'process raft request' (duration: 99.757509ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:11.690773Z","caller":"traceutil/trace.go:171","msg":"trace[825038119] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"192.833675ms","start":"2026-04-14T07:11:11.497908Z","end":"2026-04-14T07:11:11.690742Z","steps":["trace[825038119] 'process raft request' (duration: 192.568267ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:11.691078Z","caller":"traceutil/trace.go:171","msg":"trace[195024368] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"193.061153ms","start":"2026-04-14T07:11:11.497987Z","end":"2026-04-14T07:11:11.691048Z","steps":["trace[195024368] 'process raft request' (duration: 192.689851ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:11.691174Z","caller":"traceutil/trace.go:171","msg":"trace[2090920531] linearizableReadLoop","detail":"{readStateIndex:27108; appliedIndex:27103; }","duration":"121.834647ms","start":"2026-04-14T07:11:11.569323Z","end":"2026-04-14T07:11:11.691157Z","steps":["trace[2090920531] 'read index received' (duration: 26.3708ms)","trace[2090920531] 'applied index is now lower than readState.Index' (duration: 95.462997ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:11:11.691249Z","caller":"traceutil/trace.go:171","msg":"trace[340147299] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"192.495587ms","start":"2026-04-14T07:11:11.498729Z","end":"2026-04-14T07:11:11.691225Z","steps":["trace[340147299] 'process raft request' (duration: 192.25065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:11.691315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.981731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:11:11.691342Z","caller":"traceutil/trace.go:171","msg":"trace[1267602688] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"191.264511ms","start":"2026-04-14T07:11:11.500061Z","end":"2026-04-14T07:11:11.691325Z","steps":["trace[1267602688] 'process raft request' (duration: 191.039915ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:11.691355Z","caller":"traceutil/trace.go:171","msg":"trace[375252554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26250; }","duration":"122.066614ms","start":"2026-04-14T07:11:11.569277Z","end":"2026-04-14T07:11:11.691344Z","steps":["trace[375252554] 'agreement among raft nodes before linearized reading' (duration: 121.965301ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:12.138915Z","caller":"traceutil/trace.go:171","msg":"trace[1560405947] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"116.737818ms","start":"2026-04-14T07:11:12.02214Z","end":"2026-04-14T07:11:12.138877Z","steps":["trace[1560405947] 'process raft request' (duration: 116.371408ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:12.475524Z","caller":"traceutil/trace.go:171","msg":"trace[1377254091] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"267.241904ms","start":"2026-04-14T07:11:12.208244Z","end":"2026-04-14T07:11:12.475485Z","steps":["trace[1377254091] 'process raft request' (duration: 231.877333ms)","trace[1377254091] 'compare' (duration: 34.913087ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:11:12.475656Z","caller":"traceutil/trace.go:171","msg":"trace[1574103984] linearizableReadLoop","detail":"{readStateIndex:27113; appliedIndex:27112; }","duration":"230.840884ms","start":"2026-04-14T07:11:12.244801Z","end":"2026-04-14T07:11:12.475642Z","steps":["trace[1574103984] 'read index received' (duration: 195.334839ms)","trace[1574103984] 'applied index is now lower than readState.Index' (duration: 35.505375ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:11:12.475829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.018348ms","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-04-14T07:11:12.475847Z","caller":"traceutil/trace.go:171","msg":"trace[1775427780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26255; }","duration":"231.09039ms","start":"2026-04-14T07:11:12.24475Z","end":"2026-04-14T07:11:12.47584Z","steps":["trace[1775427780] 'agreement among raft nodes before linearized reading' (duration: 231.004818ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:18.886087Z","caller":"traceutil/trace.go:171","msg":"trace[862430824] transaction","detail":"{read_only:false; response_revision:26308; number_of_response:1; }","duration":"101.336365ms","start":"2026-04-14T07:11:18.784724Z","end":"2026-04-14T07:11:18.886061Z","steps":["trace[862430824] 'process raft request' (duration: 101.106119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:11:52.409578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.3827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:11:52.40972Z","caller":"traceutil/trace.go:171","msg":"trace[955897674] transaction","detail":"{read_only:false; response_revision:26712; number_of_response:1; }","duration":"207.125058ms","start":"2026-04-14T07:11:52.202572Z","end":"2026-04-14T07:11:52.409698Z","steps":["trace[955897674] 'process raft request' (duration: 73.533512ms)","trace[955897674] 'compare' (duration: 133.243576ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:11:52.745285Z","caller":"traceutil/trace.go:171","msg":"trace[1587366259] transaction","detail":"{read_only:false; response_revision:26714; number_of_response:1; }","duration":"112.59555ms","start":"2026-04-14T07:11:52.632664Z","end":"2026-04-14T07:11:52.74526Z","steps":["trace[1587366259] 'process raft request' (duration: 112.377674ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:52.745687Z","caller":"traceutil/trace.go:171","msg":"trace[736071853] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"112.410735ms","start":"2026-04-14T07:11:52.633241Z","end":"2026-04-14T07:11:52.745652Z","steps":["trace[736071853] 'process raft request' (duration: 111.971762ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:52.971903Z","caller":"traceutil/trace.go:171","msg":"trace[537475585] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"221.409181ms","start":"2026-04-14T07:11:52.750457Z","end":"2026-04-14T07:11:52.971866Z","steps":["trace[537475585] 'process raft request' (duration: 221.129753ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:52.990686Z","caller":"traceutil/trace.go:171","msg":"trace[1859451785] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"239.566854ms","start":"2026-04-14T07:11:52.751091Z","end":"2026-04-14T07:11:52.990658Z","steps":["trace[1859451785] 'process raft request' (duration: 239.391709ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:53.613788Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006bc7.wal"} {"level":"info","ts":"2026-04-14T07:14:29.036889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25211} {"level":"info","ts":"2026-04-14T07:14:29.176483Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25211,"took":"135.572302ms","hash":3917312245,"current-db-size-bytes":60821504,"current-db-size":"61 MB","current-db-size-in-use-bytes":38002688,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-04-14T07:14:29.17654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3917312245,"revision":25211,"compact-revision":21970} {"level":"info","ts":"2026-04-14T07:16:00.265765Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T07:16:00.269959Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-14T07:16:00.270061Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-14T07:19:29.042826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28228} {"level":"info","ts":"2026-04-14T07:19:29.178434Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28228,"took":"131.345769ms","hash":3776836861,"current-db-size-bytes":60821504,"current-db-size":"61 MB","current-db-size-in-use-bytes":32063488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T07:19:29.178503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3776836861,"revision":28228,"compact-revision":25211} {"level":"info","ts":"2026-04-14T07:24:29.049853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30603} {"level":"info","ts":"2026-04-14T07:24:29.177904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30603,"took":"124.726579ms","hash":1792483519,"current-db-size-bytes":60821504,"current-db-size":"61 MB","current-db-size-in-use-bytes":30126080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T07:24:29.177962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1792483519,"revision":30603,"compact-revision":28228} {"level":"info","ts":"2026-04-14T07:29:29.057066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32869} {"level":"info","ts":"2026-04-14T07:29:29.201231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32869,"took":"140.876319ms","hash":79009657,"current-db-size-bytes":61861888,"current-db-size":"62 MB","current-db-size-in-use-bytes":30765056,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-14T07:29:29.2013Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":79009657,"revision":32869,"compact-revision":30603} {"level":"info","ts":"2026-04-14T07:34:29.065485Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35172} {"level":"info","ts":"2026-04-14T07:34:29.213607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35172,"took":"142.753303ms","hash":1229110457,"current-db-size-bytes":62287872,"current-db-size":"62 MB","current-db-size-in-use-bytes":31326208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-14T07:34:29.213662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1229110457,"revision":35172,"compact-revision":32869} {"level":"info","ts":"2026-04-14T07:37:25.523287Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T07:37:25.528816Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-14T07:37:25.528956Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-14T07:39:29.072849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37408} {"level":"info","ts":"2026-04-14T07:39:29.191257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37408,"took":"115.64645ms","hash":3813573108,"current-db-size-bytes":63557632,"current-db-size":"64 MB","current-db-size-in-use-bytes":31657984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T07:39:29.191315Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3813573108,"revision":37408,"compact-revision":35172}