{"level":"warn","ts":"2026-04-03T02:23:57.104361Z","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-03T02:23:57.104456Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-03T02:23:57.104529Z","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-03T02:23:57.104547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-03T02:23:57.104575Z","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-03T02:23:57.105424Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-04-03T02:23:57.105517Z","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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-03T02:23:57.110561Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.723314ms"} {"level":"info","ts":"2026-04-03T02:23:57.117999Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-04-03T02:23:57.118166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T02:23:57.1182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-04-03T02:23:57.118215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T02:23:57.118229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-04-03T02:23:57.118274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-04-03T02:23:57.12363Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T02:23:57.125618Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T02:23:57.126988Z","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-03T02:23:57.131696Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T02:23:57.131915Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T02:23:57.132084Z","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-03T02:23:57.132256Z","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-03T02:23:57.132279Z","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-03T02:23:57.132945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-04-03T02:23:57.13423Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-03T02:23:57.136113Z","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-03T02:23:57.136179Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-03T02:23:57.136203Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-03T02:23:57.136404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T02:23:57.136442Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T02:23:57.719287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T02:23:57.719345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T02:23:57.719383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-04-03T02:23:57.719407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-04-03T02:23:57.719417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-03T02:23:57.719436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-04-03T02:23:57.71945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-03T02:23:57.720561Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:23:57.721366Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T02:23:57.721565Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:23:57.721635Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:23:57.72189Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:23:57.722038Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:23:57.722077Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:23:57.722084Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T02:23:57.72216Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T02:23:57.725589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-04-03T02:23:57.726375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-03T02:24:16.65699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.581611ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-03T02:24:16.657104Z","caller":"traceutil/trace.go:171","msg":"trace[1292890125] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"315.404837ms","start":"2026-04-03T02:24:16.341683Z","end":"2026-04-03T02:24:16.657088Z","steps":["trace[1292890125] 'read index received' (duration: 124.669973ms)","trace[1292890125] 'applied index is now lower than readState.Index' (duration: 190.733644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:24:16.657177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.49058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:24:16.657197Z","caller":"traceutil/trace.go:171","msg":"trace[4894452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"315.570262ms","start":"2026-04-03T02:24:16.341621Z","end":"2026-04-03T02:24:16.657192Z","steps":["trace[4894452] 'agreement among raft nodes before linearized reading' (duration: 315.510861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:24:16.657223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:24:16.341586Z","time spent":"315.629874ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T02:24:27.444433Z","caller":"traceutil/trace.go:171","msg":"trace[1104151405] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"102.887766ms","start":"2026-04-03T02:24:27.34153Z","end":"2026-04-03T02:24:27.444418Z","steps":["trace[1104151405] 'process raft request' (duration: 100.501928ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:24:27.444736Z","caller":"traceutil/trace.go:171","msg":"trace[1260439051] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"102.828714ms","start":"2026-04-03T02:24:27.34188Z","end":"2026-04-03T02:24:27.444708Z","steps":["trace[1260439051] 'process raft request' (duration: 102.510445ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:24:27.84283Z","caller":"traceutil/trace.go:171","msg":"trace[592003810] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"233.987771ms","start":"2026-04-03T02:24:27.608818Z","end":"2026-04-03T02:24:27.842806Z","steps":["trace[592003810] 'process raft request' (duration: 216.65552ms)","trace[592003810] 'compare' (duration: 17.177076ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:24:27.849839Z","caller":"traceutil/trace.go:171","msg":"trace[1515172654] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"227.927058ms","start":"2026-04-03T02:24:27.621896Z","end":"2026-04-03T02:24:27.849823Z","steps":["trace[1515172654] 'process raft request' (duration: 227.842956ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:24:27.849926Z","caller":"traceutil/trace.go:171","msg":"trace[242667366] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"234.02631ms","start":"2026-04-03T02:24:27.615854Z","end":"2026-04-03T02:24:27.84988Z","steps":["trace[242667366] 'process raft request' (duration: 233.751503ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:24:33.723113Z","caller":"traceutil/trace.go:171","msg":"trace[1299037392] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"146.420679ms","start":"2026-04-03T02:24:33.576664Z","end":"2026-04-03T02:24:33.723085Z","steps":["trace[1299037392] 'process raft request' (duration: 113.372502ms)","trace[1299037392] 'compare' (duration: 32.842601ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:24:34.234712Z","caller":"traceutil/trace.go:171","msg":"trace[413822145] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"413.341569ms","start":"2026-04-03T02:24:33.821338Z","end":"2026-04-03T02:24:34.23468Z","steps":["trace[413822145] 'process raft request' (duration: 413.241866ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:24:34.234737Z","caller":"traceutil/trace.go:171","msg":"trace[158435633] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"421.913112ms","start":"2026-04-03T02:24:33.812796Z","end":"2026-04-03T02:24:34.234709Z","steps":["trace[158435633] 'process raft request' (duration: 415.835132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:24:34.235598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:24:33.821302Z","time spent":"413.496854ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:24:34.235661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:24:33.812775Z","time spent":"422.055637ms","remote":"127.0.0.1:49472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T02:24:49.845287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.096705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:24:49.845397Z","caller":"traceutil/trace.go:171","msg":"trace[1026706330] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"208.913382ms","start":"2026-04-03T02:24:49.636468Z","end":"2026-04-03T02:24:49.845381Z","steps":["trace[1026706330] 'read index received' (duration: 64.566999ms)","trace[1026706330] 'applied index is now lower than readState.Index' (duration: 144.345293ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:24:49.845444Z","caller":"traceutil/trace.go:171","msg":"trace[785923231] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"254.950645ms","start":"2026-04-03T02:24:49.59047Z","end":"2026-04-03T02:24:49.845421Z","steps":["trace[785923231] 'process raft request' (duration: 110.642064ms)","trace[785923231] 'compare' (duration: 143.972172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:24:49.845567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.092098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-03T02:24:49.845615Z","caller":"traceutil/trace.go:171","msg":"trace[112546111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"209.169619ms","start":"2026-04-03T02:24:49.636431Z","end":"2026-04-03T02:24:49.845601Z","steps":["trace[112546111] 'agreement among raft nodes before linearized reading' (duration: 209.040436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:24:49.845659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.165181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-03T02:24:49.845703Z","caller":"traceutil/trace.go:171","msg":"trace[1482537297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"208.275634ms","start":"2026-04-03T02:24:49.637414Z","end":"2026-04-03T02:24:49.845689Z","steps":["trace[1482537297] 'agreement among raft nodes before linearized reading' (duration: 208.115309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:16.239853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.580502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:25:16.239962Z","caller":"traceutil/trace.go:171","msg":"trace[390996757] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"244.176615ms","start":"2026-04-03T02:25:15.995771Z","end":"2026-04-03T02:25:16.239948Z","steps":["trace[390996757] 'read index received' (duration: 44.397607ms)","trace[390996757] 'applied index is now lower than readState.Index' (duration: 199.778218ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:25:16.240023Z","caller":"traceutil/trace.go:171","msg":"trace[1429552623] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"246.944742ms","start":"2026-04-03T02:25:15.993071Z","end":"2026-04-03T02:25:16.240015Z","steps":["trace[1429552623] 'process raft request' (duration: 47.120593ms)","trace[1429552623] 'compare' (duration: 199.456969ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:25:16.240292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.495254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-03T02:25:16.240377Z","caller":"traceutil/trace.go:171","msg":"trace[212094152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1127; }","duration":"244.623638ms","start":"2026-04-03T02:25:15.995736Z","end":"2026-04-03T02:25:16.24036Z","steps":["trace[212094152] 'agreement among raft nodes before linearized reading' (duration: 244.36692ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:16.265415Z","caller":"traceutil/trace.go:171","msg":"trace[662650046] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"186.512738ms","start":"2026-04-03T02:25:16.078871Z","end":"2026-04-03T02:25:16.265384Z","steps":["trace[662650046] 'process raft request' (duration: 186.374254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:25:16.279037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.463463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-03T02:25:16.27911Z","caller":"traceutil/trace.go:171","msg":"trace[373018183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1129; }","duration":"282.559075ms","start":"2026-04-03T02:25:15.996534Z","end":"2026-04-03T02:25:16.279093Z","steps":["trace[373018183] 'agreement among raft nodes before linearized reading' (duration: 282.38397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:02.872974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.422299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:26:02.873199Z","caller":"traceutil/trace.go:171","msg":"trace[330969921] linearizableReadLoop","detail":"{readStateIndex:1862; appliedIndex:1861; }","duration":"362.976201ms","start":"2026-04-03T02:26:02.5102Z","end":"2026-04-03T02:26:02.873176Z","steps":["trace[330969921] 'read index received' (duration: 121.326006ms)","trace[330969921] 'applied index is now lower than readState.Index' (duration: 241.648585ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:02.873313Z","caller":"traceutil/trace.go:171","msg":"trace[1078421265] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"385.133835ms","start":"2026-04-03T02:26:02.488139Z","end":"2026-04-03T02:26:02.873273Z","steps":["trace[1078421265] 'process raft request' (duration: 143.313815ms)","trace[1078421265] 'compare' (duration: 241.293726ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:26:02.873427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.216959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-03T02:26:02.873479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:02.488114Z","time spent":"385.2779ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:26:02.873515Z","caller":"traceutil/trace.go:171","msg":"trace[123416159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1796; }","duration":"363.350353ms","start":"2026-04-03T02:26:02.510143Z","end":"2026-04-03T02:26:02.873494Z","steps":["trace[123416159] 'agreement among raft nodes before linearized reading' (duration: 363.116406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:02.873575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:02.510122Z","time spent":"363.432425ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T02:26:02.873555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.527822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-03T02:26:02.873573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.55823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:26:02.873648Z","caller":"traceutil/trace.go:171","msg":"trace[254461933] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1796; }","duration":"344.684406ms","start":"2026-04-03T02:26:02.528942Z","end":"2026-04-03T02:26:02.873627Z","steps":["trace[254461933] 'agreement among raft nodes before linearized reading' (duration: 344.498801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:02.873754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:02.528921Z","time spent":"344.81737ms","remote":"127.0.0.1:49060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":20,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-03T02:26:02.873734Z","caller":"traceutil/trace.go:171","msg":"trace[549858344] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1796; }","duration":"294.857538ms","start":"2026-04-03T02:26:02.578833Z","end":"2026-04-03T02:26:02.87369Z","steps":["trace[549858344] 'agreement among raft nodes before linearized reading' (duration: 294.599591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:02.873874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.180917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-03T02:26:02.874127Z","caller":"traceutil/trace.go:171","msg":"trace[1915367988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1796; }","duration":"355.415503ms","start":"2026-04-03T02:26:02.518656Z","end":"2026-04-03T02:26:02.874071Z","steps":["trace[1915367988] 'agreement among raft nodes before linearized reading' (duration: 354.698143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:02.874302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:02.518641Z","time spent":"355.642199ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-03T02:26:48.193431Z","caller":"traceutil/trace.go:171","msg":"trace[648643716] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"107.638351ms","start":"2026-04-03T02:26:48.085759Z","end":"2026-04-03T02:26:48.193397Z","steps":["trace[648643716] 'process raft request' (duration: 107.443684ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:53.448334Z","caller":"traceutil/trace.go:171","msg":"trace[1529457834] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2197; }","duration":"106.973086ms","start":"2026-04-03T02:26:53.341332Z","end":"2026-04-03T02:26:53.448305Z","steps":["trace[1529457834] 'read index received' (duration: 42.52291ms)","trace[1529457834] 'applied index is now lower than readState.Index' (duration: 64.448826ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:53.448438Z","caller":"traceutil/trace.go:171","msg":"trace[1940798081] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"143.245588ms","start":"2026-04-03T02:26:53.30515Z","end":"2026-04-03T02:26:53.448396Z","steps":["trace[1940798081] 'process raft request' (duration: 78.765651ms)","trace[1940798081] 'compare' (duration: 64.246438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:26:53.448659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.139932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:26:53.448707Z","caller":"traceutil/trace.go:171","msg":"trace[1513992873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2121; }","duration":"107.418132ms","start":"2026-04-03T02:26:53.341277Z","end":"2026-04-03T02:26:53.448695Z","steps":["trace[1513992873] 'agreement among raft nodes before linearized reading' (duration: 107.150222ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:30:47.634609Z","caller":"traceutil/trace.go:171","msg":"trace[982906766] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"139.141096ms","start":"2026-04-03T02:30:47.495436Z","end":"2026-04-03T02:30:47.634577Z","steps":["trace[982906766] 'process raft request' (duration: 138.718484ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:13.294167Z","caller":"traceutil/trace.go:171","msg":"trace[273960326] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"103.50006ms","start":"2026-04-03T02:32:13.190641Z","end":"2026-04-03T02:32:13.294141Z","steps":["trace[273960326] 'process raft request' (duration: 97.290042ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:18.537256Z","caller":"traceutil/trace.go:171","msg":"trace[1668115736] linearizableReadLoop","detail":"{readStateIndex:5018; appliedIndex:5017; }","duration":"105.755002ms","start":"2026-04-03T02:32:18.431474Z","end":"2026-04-03T02:32:18.537229Z","steps":["trace[1668115736] 'read index received' (duration: 105.535266ms)","trace[1668115736] 'applied index is now lower than readState.Index' (duration: 218.306µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:32:18.5373Z","caller":"traceutil/trace.go:171","msg":"trace[1643631745] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"110.06019ms","start":"2026-04-03T02:32:18.427205Z","end":"2026-04-03T02:32:18.537265Z","steps":["trace[1643631745] 'process raft request' (duration: 109.867904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:18.537573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.06917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11455"} {"level":"info","ts":"2026-04-03T02:32:18.537618Z","caller":"traceutil/trace.go:171","msg":"trace[451486452] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4837; }","duration":"106.140772ms","start":"2026-04-03T02:32:18.431465Z","end":"2026-04-03T02:32:18.537606Z","steps":["trace[451486452] 'agreement among raft nodes before linearized reading' (duration: 105.922616ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:21.669147Z","caller":"traceutil/trace.go:171","msg":"trace[465781517] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"136.190218ms","start":"2026-04-03T02:32:21.532905Z","end":"2026-04-03T02:32:21.669095Z","steps":["trace[465781517] 'process raft request' (duration: 136.001263ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:41.377817Z","caller":"traceutil/trace.go:171","msg":"trace[966775247] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"173.385448ms","start":"2026-04-03T02:32:41.204385Z","end":"2026-04-03T02:32:41.37777Z","steps":["trace[966775247] 'process raft request' (duration: 150.445695ms)","trace[966775247] 'compare' (duration: 22.706827ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:32:41.387817Z","caller":"traceutil/trace.go:171","msg":"trace[1710318880] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"183.01341ms","start":"2026-04-03T02:32:41.204776Z","end":"2026-04-03T02:32:41.38779Z","steps":["trace[1710318880] 'process raft request' (duration: 182.816615ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:41.387939Z","caller":"traceutil/trace.go:171","msg":"trace[522298628] linearizableReadLoop","detail":"{readStateIndex:5538; appliedIndex:5535; }","duration":"168.040672ms","start":"2026-04-03T02:32:41.219866Z","end":"2026-04-03T02:32:41.387907Z","steps":["trace[522298628] 'read index received' (duration: 134.914353ms)","trace[522298628] 'applied index is now lower than readState.Index' (duration: 33.123299ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:32:41.388061Z","caller":"traceutil/trace.go:171","msg":"trace[1281631191] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"182.992309ms","start":"2026-04-03T02:32:41.205056Z","end":"2026-04-03T02:32:41.388048Z","steps":["trace[1281631191] 'process raft request' (duration: 182.687871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:41.388418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.536016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d57994a3-ea9e-4899-a136-828b1a3319b5\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-03T02:32:41.388522Z","caller":"traceutil/trace.go:171","msg":"trace[462055778] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d57994a3-ea9e-4899-a136-828b1a3319b5; range_end:; response_count:1; response_revision:5344; }","duration":"168.68498ms","start":"2026-04-03T02:32:41.219812Z","end":"2026-04-03T02:32:41.388497Z","steps":["trace[462055778] 'agreement among raft nodes before linearized reading' (duration: 168.414083ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:41.809661Z","caller":"traceutil/trace.go:171","msg":"trace[1880591563] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"214.3676ms","start":"2026-04-03T02:32:41.595266Z","end":"2026-04-03T02:32:41.809634Z","steps":["trace[1880591563] 'process raft request' (duration: 214.162685ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:41.839524Z","caller":"traceutil/trace.go:171","msg":"trace[1170415956] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"191.043988ms","start":"2026-04-03T02:32:41.648447Z","end":"2026-04-03T02:32:41.839491Z","steps":["trace[1170415956] 'process raft request' (duration: 190.807591ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:33:57.762065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2987} {"level":"info","ts":"2026-04-03T02:33:57.843566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2987,"took":"80.130066ms","hash":929663249,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":33800192,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-03T02:33:57.843675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":929663249,"revision":2987,"compact-revision":-1} {"level":"info","ts":"2026-04-03T02:36:06.891276Z","caller":"traceutil/trace.go:171","msg":"trace[278901669] transaction","detail":"{read_only:false; response_revision:6880; number_of_response:1; }","duration":"186.815567ms","start":"2026-04-03T02:36:06.704439Z","end":"2026-04-03T02:36:06.891254Z","steps":["trace[278901669] 'process raft request' (duration: 124.835569ms)","trace[278901669] 'compare' (duration: 61.889455ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:37:10.829344Z","caller":"traceutil/trace.go:171","msg":"trace[251872214] linearizableReadLoop","detail":"{readStateIndex:7675; appliedIndex:7674; }","duration":"218.108744ms","start":"2026-04-03T02:37:10.611209Z","end":"2026-04-03T02:37:10.829318Z","steps":["trace[251872214] 'read index received' (duration: 217.895229ms)","trace[251872214] 'applied index is now lower than readState.Index' (duration: 212.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:37:10.829462Z","caller":"traceutil/trace.go:171","msg":"trace[145671649] transaction","detail":"{read_only:false; response_revision:7414; number_of_response:1; }","duration":"234.002309ms","start":"2026-04-03T02:37:10.595398Z","end":"2026-04-03T02:37:10.829401Z","steps":["trace[145671649] 'process raft request' (duration: 233.725931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:10.829746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.510165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:37:10.829872Z","caller":"traceutil/trace.go:171","msg":"trace[1175236275] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:7414; }","duration":"218.69246ms","start":"2026-04-03T02:37:10.611155Z","end":"2026-04-03T02:37:10.829847Z","steps":["trace[1175236275] 'agreement among raft nodes before linearized reading' (duration: 218.461843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:10.829799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.935999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:37:10.830036Z","caller":"traceutil/trace.go:171","msg":"trace[1814062545] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:7414; }","duration":"135.190406ms","start":"2026-04-03T02:37:10.694784Z","end":"2026-04-03T02:37:10.829974Z","steps":["trace[1814062545] 'agreement among raft nodes before linearized reading' (duration: 134.929799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:21.023612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.808603ms","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-03T02:37:21.023738Z","caller":"traceutil/trace.go:171","msg":"trace[741743944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7491; }","duration":"102.02278ms","start":"2026-04-03T02:37:20.921686Z","end":"2026-04-03T02:37:21.023709Z","steps":["trace[741743944] 'range keys from in-memory index tree' (duration: 101.518736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:23.454967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.49015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:37:23.455058Z","caller":"traceutil/trace.go:171","msg":"trace[607831732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7518; }","duration":"114.625284ms","start":"2026-04-03T02:37:23.340411Z","end":"2026-04-03T02:37:23.455037Z","steps":["trace[607831732] 'agreement among raft nodes before linearized reading' (duration: 114.452729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:23.455168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.341985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:37:23.455268Z","caller":"traceutil/trace.go:171","msg":"trace[1869389337] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:7518; }","duration":"126.471108ms","start":"2026-04-03T02:37:23.328779Z","end":"2026-04-03T02:37:23.45525Z","steps":["trace[1869389337] 'agreement among raft nodes before linearized reading' (duration: 126.327324ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:37:26.478374Z","caller":"traceutil/trace.go:171","msg":"trace[2046365751] linearizableReadLoop","detail":"{readStateIndex:7808; appliedIndex:7807; }","duration":"124.143194ms","start":"2026-04-03T02:37:26.354197Z","end":"2026-04-03T02:37:26.478341Z","steps":["trace[2046365751] 'read index received' (duration: 123.897238ms)","trace[2046365751] 'applied index is now lower than readState.Index' (duration: 244.646µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:37:26.478466Z","caller":"traceutil/trace.go:171","msg":"trace[357288113] transaction","detail":"{read_only:false; response_revision:7543; number_of_response:1; }","duration":"131.210827ms","start":"2026-04-03T02:37:26.347219Z","end":"2026-04-03T02:37:26.47843Z","steps":["trace[357288113] 'process raft request' (duration: 130.884828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:26.478621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.419262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cjlj4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-03T02:37:26.478655Z","caller":"traceutil/trace.go:171","msg":"trace[2071150501] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cjlj4; range_end:; response_count:1; response_revision:7543; }","duration":"124.502524ms","start":"2026-04-03T02:37:26.354142Z","end":"2026-04-03T02:37:26.478645Z","steps":["trace[2071150501] 'agreement among raft nodes before linearized reading' (duration: 124.319749ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:37:33.786912Z","caller":"traceutil/trace.go:171","msg":"trace[774766695] linearizableReadLoop","detail":"{readStateIndex:7849; appliedIndex:7848; }","duration":"178.422778ms","start":"2026-04-03T02:37:33.608449Z","end":"2026-04-03T02:37:33.786872Z","steps":["trace[774766695] 'read index received' (duration: 81.166298ms)","trace[774766695] 'applied index is now lower than readState.Index' (duration: 97.25476ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:37:33.787004Z","caller":"traceutil/trace.go:171","msg":"trace[1782428735] transaction","detail":"{read_only:false; response_revision:7583; number_of_response:1; }","duration":"214.299939ms","start":"2026-04-03T02:37:33.572671Z","end":"2026-04-03T02:37:33.786971Z","steps":["trace[1782428735] 'process raft request' (duration: 117.005299ms)","trace[1782428735] 'compare' (duration: 96.949791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:37:33.78716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.088485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:37:33.787221Z","caller":"traceutil/trace.go:171","msg":"trace[6181794] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:7583; }","duration":"108.214189ms","start":"2026-04-03T02:37:33.678994Z","end":"2026-04-03T02:37:33.787208Z","steps":["trace[6181794] 'agreement among raft nodes before linearized reading' (duration: 108.084995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:33.78736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.626824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-03T02:37:33.787461Z","caller":"traceutil/trace.go:171","msg":"trace[1119143774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7583; }","duration":"179.027015ms","start":"2026-04-03T02:37:33.60841Z","end":"2026-04-03T02:37:33.787437Z","steps":["trace[1119143774] 'agreement among raft nodes before linearized reading' (duration: 178.612964ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:24.941684Z","caller":"traceutil/trace.go:171","msg":"trace[1713038687] transaction","detail":"{read_only:false; response_revision:7913; number_of_response:1; }","duration":"103.683235ms","start":"2026-04-03T02:38:24.837962Z","end":"2026-04-03T02:38:24.941645Z","steps":["trace[1713038687] 'process raft request' (duration: 90.132894ms)","trace[1713038687] 'compare' (duration: 13.1525ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:27.482968Z","caller":"traceutil/trace.go:171","msg":"trace[1340382790] transaction","detail":"{read_only:false; response_revision:7943; number_of_response:1; }","duration":"190.107218ms","start":"2026-04-03T02:38:27.292842Z","end":"2026-04-03T02:38:27.482949Z","steps":["trace[1340382790] 'process raft request' (duration: 190.076438ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:27.482999Z","caller":"traceutil/trace.go:171","msg":"trace[741651170] transaction","detail":"{read_only:false; response_revision:7941; number_of_response:1; }","duration":"190.322334ms","start":"2026-04-03T02:38:27.292647Z","end":"2026-04-03T02:38:27.482969Z","steps":["trace[741651170] 'process raft request' (duration: 123.23992ms)","trace[741651170] 'compare' (duration: 66.869988ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:27.48321Z","caller":"traceutil/trace.go:171","msg":"trace[691113945] transaction","detail":"{read_only:false; response_revision:7942; number_of_response:1; }","duration":"190.484138ms","start":"2026-04-03T02:38:27.29272Z","end":"2026-04-03T02:38:27.483204Z","steps":["trace[691113945] 'process raft request' (duration: 190.17103ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:27.569717Z","caller":"traceutil/trace.go:171","msg":"trace[529039036] linearizableReadLoop","detail":"{readStateIndex:8223; appliedIndex:8219; }","duration":"273.073248ms","start":"2026-04-03T02:38:27.296621Z","end":"2026-04-03T02:38:27.569694Z","steps":["trace[529039036] 'read index received' (duration: 119.352514ms)","trace[529039036] 'applied index is now lower than readState.Index' (duration: 153.719414ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:27.569795Z","caller":"traceutil/trace.go:171","msg":"trace[16618503] transaction","detail":"{read_only:false; response_revision:7945; number_of_response:1; }","duration":"267.543306ms","start":"2026-04-03T02:38:27.302229Z","end":"2026-04-03T02:38:27.569772Z","steps":["trace[16618503] 'process raft request' (duration: 267.380521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:27.569907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.138866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:38:27.569968Z","caller":"traceutil/trace.go:171","msg":"trace[556761639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7945; }","duration":"229.247839ms","start":"2026-04-03T02:38:27.340705Z","end":"2026-04-03T02:38:27.569953Z","steps":["trace[556761639] 'agreement among raft nodes before linearized reading' (duration: 229.138706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:27.569981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.327394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-03T02:38:27.57007Z","caller":"traceutil/trace.go:171","msg":"trace[555460109] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:7945; }","duration":"273.445127ms","start":"2026-04-03T02:38:27.296608Z","end":"2026-04-03T02:38:27.570053Z","steps":["trace[555460109] 'agreement among raft nodes before linearized reading' (duration: 273.212411ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:27.570136Z","caller":"traceutil/trace.go:171","msg":"trace[2135403077] transaction","detail":"{read_only:false; response_revision:7944; number_of_response:1; }","duration":"276.000027ms","start":"2026-04-03T02:38:27.29409Z","end":"2026-04-03T02:38:27.57009Z","steps":["trace[2135403077] 'process raft request' (duration: 275.390981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:27.571019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.216487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210989"} {"level":"info","ts":"2026-04-03T02:38:27.571107Z","caller":"traceutil/trace.go:171","msg":"trace[1528524276] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:7945; }","duration":"222.345089ms","start":"2026-04-03T02:38:27.348744Z","end":"2026-04-03T02:38:27.571089Z","steps":["trace[1528524276] 'agreement among raft nodes before linearized reading' (duration: 221.126536ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:27.842377Z","caller":"traceutil/trace.go:171","msg":"trace[706052165] linearizableReadLoop","detail":"{readStateIndex:8230; appliedIndex:8228; }","duration":"111.277473ms","start":"2026-04-03T02:38:27.731074Z","end":"2026-04-03T02:38:27.842351Z","steps":["trace[706052165] 'read index received' (duration: 59.116817ms)","trace[706052165] 'applied index is now lower than readState.Index' (duration: 52.159055ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:27.842401Z","caller":"traceutil/trace.go:171","msg":"trace[379440120] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7950; }","duration":"116.872616ms","start":"2026-04-03T02:38:27.725503Z","end":"2026-04-03T02:38:27.842376Z","steps":["trace[379440120] 'process raft request' (duration: 116.729612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:27.842834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.700265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-04-03T02:38:27.843054Z","caller":"traceutil/trace.go:171","msg":"trace[108581612] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:7950; }","duration":"111.965251ms","start":"2026-04-03T02:38:27.73105Z","end":"2026-04-03T02:38:27.843016Z","steps":["trace[108581612] 'agreement among raft nodes before linearized reading' (duration: 111.423397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:27.856072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.904036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"warn","ts":"2026-04-03T02:38:27.856097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.982812ms","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-03T02:38:27.856131Z","caller":"traceutil/trace.go:171","msg":"trace[1970322767] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:7951; }","duration":"124.999708ms","start":"2026-04-03T02:38:27.731119Z","end":"2026-04-03T02:38:27.856119Z","steps":["trace[1970322767] 'agreement among raft nodes before linearized reading' (duration: 124.784042ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:27.856156Z","caller":"traceutil/trace.go:171","msg":"trace[1960793352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7951; }","duration":"108.074335ms","start":"2026-04-03T02:38:27.748066Z","end":"2026-04-03T02:38:27.856141Z","steps":["trace[1960793352] 'agreement among raft nodes before linearized reading' (duration: 107.88471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:27.856323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.139032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:38:27.856353Z","caller":"traceutil/trace.go:171","msg":"trace[1342409681] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:7951; }","duration":"125.187223ms","start":"2026-04-03T02:38:27.731159Z","end":"2026-04-03T02:38:27.856346Z","steps":["trace[1342409681] 'agreement among raft nodes before linearized reading' (duration: 125.137572ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:27.969238Z","caller":"traceutil/trace.go:171","msg":"trace[1904135810] linearizableReadLoop","detail":"{readStateIndex:8233; appliedIndex:8231; }","duration":"108.153407ms","start":"2026-04-03T02:38:27.861054Z","end":"2026-04-03T02:38:27.969208Z","steps":["trace[1904135810] 'read index received' (duration: 27.556094ms)","trace[1904135810] 'applied index is now lower than readState.Index' (duration: 80.595503ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:27.969346Z","caller":"traceutil/trace.go:171","msg":"trace[314793520] transaction","detail":"{read_only:false; response_revision:7953; number_of_response:1; }","duration":"108.320892ms","start":"2026-04-03T02:38:27.86099Z","end":"2026-04-03T02:38:27.969311Z","steps":["trace[314793520] 'process raft request' (duration: 92.593351ms)","trace[314793520] 'compare' (duration: 15.261457ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:27.969385Z","caller":"traceutil/trace.go:171","msg":"trace[324122616] transaction","detail":"{read_only:false; response_revision:7954; number_of_response:1; }","duration":"108.033325ms","start":"2026-04-03T02:38:27.861312Z","end":"2026-04-03T02:38:27.969345Z","steps":["trace[324122616] 'process raft request' (duration: 107.786367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:27.969527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.449096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-03T02:38:27.96959Z","caller":"traceutil/trace.go:171","msg":"trace[746333373] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:7954; }","duration":"108.542708ms","start":"2026-04-03T02:38:27.861033Z","end":"2026-04-03T02:38:27.969575Z","steps":["trace[746333373] 'agreement among raft nodes before linearized reading' (duration: 108.345752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:28.000731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.566267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-03T02:38:28.000809Z","caller":"traceutil/trace.go:171","msg":"trace[1493104939] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:7956; }","duration":"139.662869ms","start":"2026-04-03T02:38:27.861128Z","end":"2026-04-03T02:38:28.000791Z","steps":["trace[1493104939] 'agreement among raft nodes before linearized reading' (duration: 139.31878ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:29.629557Z","caller":"traceutil/trace.go:171","msg":"trace[1717355461] transaction","detail":"{read_only:false; response_revision:7969; number_of_response:1; }","duration":"122.683095ms","start":"2026-04-03T02:38:29.50683Z","end":"2026-04-03T02:38:29.629514Z","steps":["trace[1717355461] 'process raft request' (duration: 122.382337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:31.132362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.768202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T02:38:31.132483Z","caller":"traceutil/trace.go:171","msg":"trace[1893126129] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:7979; }","duration":"286.900816ms","start":"2026-04-03T02:38:30.845554Z","end":"2026-04-03T02:38:31.132455Z","steps":["trace[1893126129] 'range keys from in-memory index tree' (duration: 286.521415ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:32.141463Z","caller":"traceutil/trace.go:171","msg":"trace[1119647991] linearizableReadLoop","detail":"{readStateIndex:8266; appliedIndex:8265; }","duration":"104.460636ms","start":"2026-04-03T02:38:32.036974Z","end":"2026-04-03T02:38:32.141434Z","steps":["trace[1119647991] 'read index received' (duration: 25.62292ms)","trace[1119647991] 'applied index is now lower than readState.Index' (duration: 78.836116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:32.14166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.666692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:38:32.141731Z","caller":"traceutil/trace.go:171","msg":"trace[53290232] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:7985; }","duration":"104.764554ms","start":"2026-04-03T02:38:32.036958Z","end":"2026-04-03T02:38:32.141723Z","steps":["trace[53290232] 'agreement among raft nodes before linearized reading' (duration: 104.578619ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:32.14186Z","caller":"traceutil/trace.go:171","msg":"trace[125018376] transaction","detail":"{read_only:false; response_revision:7984; number_of_response:1; }","duration":"116.438494ms","start":"2026-04-03T02:38:32.025389Z","end":"2026-04-03T02:38:32.141827Z","steps":["trace[125018376] 'process raft request' (duration: 37.217479ms)","trace[125018376] 'compare' (duration: 78.441624ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:42.564826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.018319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:38:42.564896Z","caller":"traceutil/trace.go:171","msg":"trace[1496552200] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:8179; }","duration":"101.115702ms","start":"2026-04-03T02:38:42.463766Z","end":"2026-04-03T02:38:42.564882Z","steps":["trace[1496552200] 'count revisions from in-memory index tree' (duration: 100.915066ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:44.357742Z","caller":"traceutil/trace.go:171","msg":"trace[1058017865] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"287.102909ms","start":"2026-04-03T02:38:44.070608Z","end":"2026-04-03T02:38:44.357711Z","steps":["trace[1058017865] 'process raft request' (duration: 286.897214ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:44.53882Z","caller":"traceutil/trace.go:171","msg":"trace[824157142] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"347.525345ms","start":"2026-04-03T02:38:44.19127Z","end":"2026-04-03T02:38:44.538795Z","steps":["trace[824157142] 'process raft request' (duration: 347.34216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:44.538981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:44.191244Z","time spent":"347.648019ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:38:44.538966Z","caller":"traceutil/trace.go:171","msg":"trace[1044875095] linearizableReadLoop","detail":"{readStateIndex:8471; appliedIndex:8469; }","duration":"147.516353ms","start":"2026-04-03T02:38:44.391419Z","end":"2026-04-03T02:38:44.538935Z","steps":["trace[1044875095] 'read index received' (duration: 147.112422ms)","trace[1044875095] 'applied index is now lower than readState.Index' (duration: 402.131µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:44.539123Z","caller":"traceutil/trace.go:171","msg":"trace[1154278768] transaction","detail":"{read_only:false; response_revision:8187; number_of_response:1; }","duration":"323.043315ms","start":"2026-04-03T02:38:44.216068Z","end":"2026-04-03T02:38:44.539111Z","steps":["trace[1154278768] 'process raft request' (duration: 322.682635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:44.539172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.75624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-03T02:38:44.539181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:44.216047Z","time spent":"323.103956ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:38:44.539219Z","caller":"traceutil/trace.go:171","msg":"trace[959580299] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8187; }","duration":"147.856302ms","start":"2026-04-03T02:38:44.391349Z","end":"2026-04-03T02:38:44.539205Z","steps":["trace[959580299] 'agreement among raft nodes before linearized reading' (duration: 147.691108ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:54.95911Z","caller":"traceutil/trace.go:171","msg":"trace[1499270296] transaction","detail":"{read_only:false; response_revision:8243; number_of_response:1; }","duration":"110.300143ms","start":"2026-04-03T02:38:54.84878Z","end":"2026-04-03T02:38:54.95908Z","steps":["trace[1499270296] 'process raft request' (duration: 110.100078ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:56.270981Z","caller":"traceutil/trace.go:171","msg":"trace[1295422618] transaction","detail":"{read_only:false; response_revision:8246; number_of_response:1; }","duration":"257.518288ms","start":"2026-04-03T02:38:56.013434Z","end":"2026-04-03T02:38:56.270953Z","steps":["trace[1295422618] 'process raft request' (duration: 256.395408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:56.869371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.648037ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-03T02:38:56.869519Z","caller":"traceutil/trace.go:171","msg":"trace[1261544741] linearizableReadLoop","detail":"{readStateIndex:8534; appliedIndex:8533; }","duration":"443.410964ms","start":"2026-04-03T02:38:56.426085Z","end":"2026-04-03T02:38:56.869496Z","steps":["trace[1261544741] 'read index received' (duration: 13.628973ms)","trace[1261544741] 'applied index is now lower than readState.Index' (duration: 429.779971ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:56.869819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.717571ms","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-03T02:38:56.869875Z","caller":"traceutil/trace.go:171","msg":"trace[2123363547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8246; }","duration":"443.830774ms","start":"2026-04-03T02:38:56.426028Z","end":"2026-04-03T02:38:56.869859Z","steps":["trace[2123363547] 'agreement among raft nodes before linearized reading' (duration: 443.564187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:56.869937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:56.426003Z","time spent":"443.920917ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T02:38:56.880917Z","caller":"traceutil/trace.go:171","msg":"trace[379977786] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"292.319572ms","start":"2026-04-03T02:38:56.588571Z","end":"2026-04-03T02:38:56.880891Z","steps":["trace[379977786] 'process raft request' (duration: 292.149207ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:56.892192Z","caller":"traceutil/trace.go:171","msg":"trace[990241549] transaction","detail":"{read_only:false; response_revision:8248; number_of_response:1; }","duration":"302.4485ms","start":"2026-04-03T02:38:56.589719Z","end":"2026-04-03T02:38:56.892167Z","steps":["trace[990241549] 'process raft request' (duration: 302.265105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:56.89236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:56.589692Z","time spent":"302.580063ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:38:56.892533Z","caller":"traceutil/trace.go:171","msg":"trace[845230048] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"169.446255ms","start":"2026-04-03T02:38:56.723067Z","end":"2026-04-03T02:38:56.892513Z","steps":["trace[845230048] 'process raft request' (duration: 169.065784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:56.892817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.465029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-03T02:38:56.892852Z","caller":"traceutil/trace.go:171","msg":"trace[686149853] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8250; }","duration":"295.520189ms","start":"2026-04-03T02:38:56.597323Z","end":"2026-04-03T02:38:56.892843Z","steps":["trace[686149853] 'agreement among raft nodes before linearized reading' (duration: 295.401536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:56.892965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.854815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-03T02:38:56.892983Z","caller":"traceutil/trace.go:171","msg":"trace[1282619660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8250; }","duration":"177.914636ms","start":"2026-04-03T02:38:56.715064Z","end":"2026-04-03T02:38:56.892978Z","steps":["trace[1282619660] 'agreement among raft nodes before linearized reading' (duration: 177.870975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:56.893048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.979917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T02:38:56.893061Z","caller":"traceutil/trace.go:171","msg":"trace[1794491243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8250; }","duration":"244.006318ms","start":"2026-04-03T02:38:56.649049Z","end":"2026-04-03T02:38:56.893056Z","steps":["trace[1794491243] 'agreement among raft nodes before linearized reading' (duration: 243.965477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:56.893113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.4749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:38:56.893124Z","caller":"traceutil/trace.go:171","msg":"trace[640698866] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8250; }","duration":"252.540532ms","start":"2026-04-03T02:38:56.64058Z","end":"2026-04-03T02:38:56.89312Z","steps":["trace[640698866] 'agreement among raft nodes before linearized reading' (duration: 252.513891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:56.893355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.240644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-03T02:38:56.893446Z","caller":"traceutil/trace.go:171","msg":"trace[1221420561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8250; }","duration":"252.385417ms","start":"2026-04-03T02:38:56.641037Z","end":"2026-04-03T02:38:56.893422Z","steps":["trace[1221420561] 'agreement among raft nodes before linearized reading' (duration: 252.048508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:57.422091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.030327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:38:57.422427Z","caller":"traceutil/trace.go:171","msg":"trace[267269625] linearizableReadLoop","detail":"{readStateIndex:8542; appliedIndex:8539; }","duration":"457.633003ms","start":"2026-04-03T02:38:56.964768Z","end":"2026-04-03T02:38:57.422401Z","steps":["trace[267269625] 'read index received' (duration: 162.178645ms)","trace[267269625] 'applied index is now lower than readState.Index' (duration: 295.453028ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:57.422529Z","caller":"traceutil/trace.go:171","msg":"trace[1502015947] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"523.92581ms","start":"2026-04-03T02:38:56.898589Z","end":"2026-04-03T02:38:57.422515Z","steps":["trace[1502015947] 'process raft request' (duration: 523.640883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:57.422618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:56.898568Z","time spent":"524.000593ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:38:57.422637Z","caller":"traceutil/trace.go:171","msg":"trace[1536340411] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"524.365523ms","start":"2026-04-03T02:38:56.898234Z","end":"2026-04-03T02:38:57.4226Z","steps":["trace[1536340411] 'process raft request' (duration: 228.692539ms)","trace[1536340411] 'compare' (duration: 294.896253ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:57.422648Z","caller":"traceutil/trace.go:171","msg":"trace[1485505754] transaction","detail":"{read_only:false; response_revision:8254; number_of_response:1; }","duration":"522.908732ms","start":"2026-04-03T02:38:56.899705Z","end":"2026-04-03T02:38:57.422614Z","steps":["trace[1485505754] 'process raft request' (duration: 522.632095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:57.423106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:56.898211Z","time spent":"524.802144ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:38:57.423106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.330813ms","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":"warn","ts":"2026-04-03T02:38:57.423152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:56.899686Z","time spent":"523.419516ms","remote":"127.0.0.1:49232","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-03T02:38:57.423186Z","caller":"traceutil/trace.go:171","msg":"trace[692714864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8254; }","duration":"458.481547ms","start":"2026-04-03T02:38:56.964692Z","end":"2026-04-03T02:38:57.423173Z","steps":["trace[692714864] 'agreement among raft nodes before linearized reading' (duration: 458.013064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:57.423232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:56.964664Z","time spent":"458.55768ms","remote":"127.0.0.1:49232","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-03T02:38:57.436934Z","caller":"traceutil/trace.go:171","msg":"trace[1412075476] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"118.169459ms","start":"2026-04-03T02:38:57.318739Z","end":"2026-04-03T02:38:57.436909Z","steps":["trace[1412075476] 'process raft request' (duration: 117.805199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:57.436968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.197788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-03T02:38:57.437063Z","caller":"traceutil/trace.go:171","msg":"trace[146481030] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8255; }","duration":"435.364123ms","start":"2026-04-03T02:38:57.001678Z","end":"2026-04-03T02:38:57.437042Z","steps":["trace[146481030] 'agreement among raft nodes before linearized reading' (duration: 435.060334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:57.437114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:57.001652Z","time spent":"435.453446ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-03T02:38:57.921289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6088} {"level":"warn","ts":"2026-04-03T02:38:58.592514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.227906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:38:58.592615Z","caller":"traceutil/trace.go:171","msg":"trace[1747834560] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:8257; }","duration":"178.572105ms","start":"2026-04-03T02:38:58.414018Z","end":"2026-04-03T02:38:58.59259Z","steps":["trace[1747834560] 'count revisions from in-memory index tree' (duration: 178.111022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:59.162548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.547596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:38:59.162735Z","caller":"traceutil/trace.go:171","msg":"trace[1321153965] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"209.176842ms","start":"2026-04-03T02:38:58.953542Z","end":"2026-04-03T02:38:59.162719Z","steps":["trace[1321153965] 'process raft request' (duration: 209.10835ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:59.162975Z","caller":"traceutil/trace.go:171","msg":"trace[1991080612] transaction","detail":"{read_only:false; response_revision:8259; number_of_response:1; }","duration":"263.809191ms","start":"2026-04-03T02:38:58.899139Z","end":"2026-04-03T02:38:59.162948Z","steps":["trace[1991080612] 'process raft request' (duration: 139.785561ms)","trace[1991080612] 'compare' (duration: 37.120609ms)","trace[1991080612] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:507; } (duration: 86.318935ms)"],"step_count":3} {"level":"warn","ts":"2026-04-03T02:38:59.163134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.678068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-03T02:38:59.163177Z","caller":"traceutil/trace.go:171","msg":"trace[297509521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8260; }","duration":"259.75144ms","start":"2026-04-03T02:38:58.903415Z","end":"2026-04-03T02:38:59.163166Z","steps":["trace[297509521] 'agreement among raft nodes before linearized reading' (duration: 259.606696ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:59.162996Z","caller":"traceutil/trace.go:171","msg":"trace[116771140] linearizableReadLoop","detail":"{readStateIndex:8548; appliedIndex:8547; }","duration":"213.531363ms","start":"2026-04-03T02:38:58.949453Z","end":"2026-04-03T02:38:59.162984Z","steps":["trace[116771140] 'read index received' (duration: 89.413951ms)","trace[116771140] 'applied index is now lower than readState.Index' (duration: 124.116142ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:59.339551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6088,"took":"1.415738755s","hash":685909850,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":22851584,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-03T02:38:59.339638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":685909850,"revision":6088,"compact-revision":2987} {"level":"info","ts":"2026-04-03T02:38:59.340135Z","caller":"traceutil/trace.go:171","msg":"trace[721293480] linearizableReadLoop","detail":"{readStateIndex:8550; appliedIndex:8549; }","duration":"166.925006ms","start":"2026-04-03T02:38:59.173188Z","end":"2026-04-03T02:38:59.340113Z","steps":["trace[721293480] 'read index received' (duration: 89.044111ms)","trace[721293480] 'applied index is now lower than readState.Index' (duration: 77.879585ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:59.340224Z","caller":"traceutil/trace.go:171","msg":"trace[1984601593] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"170.621137ms","start":"2026-04-03T02:38:59.169593Z","end":"2026-04-03T02:38:59.340214Z","steps":["trace[1984601593] 'process raft request' (duration: 92.616509ms)","trace[1984601593] 'compare' (duration: 77.784812ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:59.340366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.249613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130542"} {"level":"info","ts":"2026-04-03T02:38:59.340438Z","caller":"traceutil/trace.go:171","msg":"trace[1087561294] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:8261; }","duration":"156.350926ms","start":"2026-04-03T02:38:59.184071Z","end":"2026-04-03T02:38:59.340422Z","steps":["trace[1087561294] 'agreement among raft nodes before linearized reading' (duration: 156.189011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:59.340593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.382049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-03T02:38:59.340626Z","caller":"traceutil/trace.go:171","msg":"trace[1240325215] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8261; }","duration":"167.4616ms","start":"2026-04-03T02:38:59.173156Z","end":"2026-04-03T02:38:59.340618Z","steps":["trace[1240325215] 'agreement among raft nodes before linearized reading' (duration: 167.321156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:59.807945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.813929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:38:59.808276Z","caller":"traceutil/trace.go:171","msg":"trace[1996756711] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"374.760293ms","start":"2026-04-03T02:38:59.433488Z","end":"2026-04-03T02:38:59.808248Z","steps":["trace[1996756711] 'process raft request' (duration: 233.559673ms)","trace[1996756711] 'compare' (duration: 140.605434ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:59.808346Z","caller":"traceutil/trace.go:171","msg":"trace[1657569206] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"374.559297ms","start":"2026-04-03T02:38:59.433768Z","end":"2026-04-03T02:38:59.808327Z","steps":["trace[1657569206] 'process raft request' (duration: 374.262799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:59.808379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:59.433471Z","time spent":"374.861315ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:38:59.808408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:59.433761Z","time spent":"374.617918ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:38:59.808776Z","caller":"traceutil/trace.go:171","msg":"trace[1941882350] transaction","detail":"{read_only:false; response_revision:8265; number_of_response:1; }","duration":"366.707031ms","start":"2026-04-03T02:38:59.442053Z","end":"2026-04-03T02:38:59.80876Z","steps":["trace[1941882350] 'process raft request' (duration: 366.213018ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:59.808821Z","caller":"traceutil/trace.go:171","msg":"trace[165507941] linearizableReadLoop","detail":"{readStateIndex:8554; appliedIndex:8551; }","duration":"298.326367ms","start":"2026-04-03T02:38:59.510483Z","end":"2026-04-03T02:38:59.80881Z","steps":["trace[165507941] 'read index received' (duration: 156.539331ms)","trace[165507941] 'applied index is now lower than readState.Index' (duration: 141.786196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:59.808872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:59.44204Z","time spent":"366.784393ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:38:59.80896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.691965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:38:59.808987Z","caller":"traceutil/trace.go:171","msg":"trace[2031810212] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8265; }","duration":"106.759396ms","start":"2026-04-03T02:38:59.702219Z","end":"2026-04-03T02:38:59.808979Z","steps":["trace[2031810212] 'agreement among raft nodes before linearized reading' (duration: 106.707425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:59.809092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.567833ms","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":"warn","ts":"2026-04-03T02:38:59.809151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.623565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cjlj4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-03T02:38:59.809207Z","caller":"traceutil/trace.go:171","msg":"trace[297192497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8265; }","duration":"298.747498ms","start":"2026-04-03T02:38:59.510434Z","end":"2026-04-03T02:38:59.809181Z","steps":["trace[297192497] 'agreement among raft nodes before linearized reading' (duration: 298.44472ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:59.809256Z","caller":"traceutil/trace.go:171","msg":"trace[927528298] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cjlj4; range_end:; response_count:1; response_revision:8265; }","duration":"294.773749ms","start":"2026-04-03T02:38:59.514457Z","end":"2026-04-03T02:38:59.809231Z","steps":["trace[927528298] 'agreement among raft nodes before linearized reading' (duration: 294.462731ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:28.163693Z","caller":"traceutil/trace.go:171","msg":"trace[1537869563] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"687.605907ms","start":"2026-04-03T02:39:27.476049Z","end":"2026-04-03T02:39:28.163655Z","steps":["trace[1537869563] 'process raft request' (duration: 687.35311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:28.163933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:39:27.476031Z","time spent":"687.770622ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:39:28.169167Z","caller":"traceutil/trace.go:171","msg":"trace[1457059051] linearizableReadLoop","detail":"{readStateIndex:8763; appliedIndex:8761; }","duration":"490.914386ms","start":"2026-04-03T02:39:27.678228Z","end":"2026-04-03T02:39:28.169143Z","steps":["trace[1457059051] 'read index received' (duration: 485.22286ms)","trace[1457059051] 'applied index is now lower than readState.Index' (duration: 5.690696ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:28.169397Z","caller":"traceutil/trace.go:171","msg":"trace[165072454] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"519.025496ms","start":"2026-04-03T02:39:27.650347Z","end":"2026-04-03T02:39:28.169372Z","steps":["trace[165072454] 'process raft request' (duration: 518.606904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:28.169435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.179943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-03T02:39:28.169491Z","caller":"traceutil/trace.go:171","msg":"trace[1021687204] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8467; }","duration":"491.276616ms","start":"2026-04-03T02:39:27.678198Z","end":"2026-04-03T02:39:28.169475Z","steps":["trace[1021687204] 'agreement among raft nodes before linearized reading' (duration: 491.096631ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:28.169529Z","caller":"traceutil/trace.go:171","msg":"trace[1664771389] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"238.004533ms","start":"2026-04-03T02:39:27.931498Z","end":"2026-04-03T02:39:28.169502Z","steps":["trace[1664771389] 'process raft request' (duration: 237.605882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:28.169519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:39:27.650327Z","time spent":"519.131509ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:39:28.169527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:39:27.678174Z","time spent":"491.344488ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-03T02:39:28.169647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.219184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-03T02:39:28.169618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.080579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:39:28.169741Z","caller":"traceutil/trace.go:171","msg":"trace[474040631] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8467; }","duration":"430.231463ms","start":"2026-04-03T02:39:27.739479Z","end":"2026-04-03T02:39:28.16971Z","steps":["trace[474040631] 'agreement among raft nodes before linearized reading' (duration: 430.005657ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:28.169678Z","caller":"traceutil/trace.go:171","msg":"trace[383947388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8467; }","duration":"217.252596ms","start":"2026-04-03T02:39:27.952419Z","end":"2026-04-03T02:39:28.169672Z","steps":["trace[383947388] 'agreement among raft nodes before linearized reading' (duration: 217.169584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:28.169816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:39:27.739424Z","time spent":"430.377626ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-03T02:39:28.169839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.287497ms","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":"warn","ts":"2026-04-03T02:39:28.169737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.289073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-03T02:39:28.169836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.881135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cjlj4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-03T02:39:28.169914Z","caller":"traceutil/trace.go:171","msg":"trace[101963860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8467; }","duration":"195.521709ms","start":"2026-04-03T02:39:27.974377Z","end":"2026-04-03T02:39:28.169899Z","steps":["trace[101963860] 'agreement among raft nodes before linearized reading' (duration: 195.19786ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:28.169964Z","caller":"traceutil/trace.go:171","msg":"trace[74849765] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cjlj4; range_end:; response_count:1; response_revision:8467; }","duration":"155.082671ms","start":"2026-04-03T02:39:28.014854Z","end":"2026-04-03T02:39:28.169937Z","steps":["trace[74849765] 'agreement among raft nodes before linearized reading' (duration: 154.67874ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:28.169892Z","caller":"traceutil/trace.go:171","msg":"trace[586318001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8467; }","duration":"166.358079ms","start":"2026-04-03T02:39:28.003516Z","end":"2026-04-03T02:39:28.169874Z","steps":["trace[586318001] 'agreement among raft nodes before linearized reading' (duration: 166.272437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:40.890627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.461928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:39:40.890745Z","caller":"traceutil/trace.go:171","msg":"trace[380896484] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:8576; }","duration":"153.607763ms","start":"2026-04-03T02:39:40.737108Z","end":"2026-04-03T02:39:40.890716Z","steps":["trace[380896484] 'count revisions from in-memory index tree' (duration: 153.399827ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:40.895928Z","caller":"traceutil/trace.go:171","msg":"trace[654585476] linearizableReadLoop","detail":"{readStateIndex:8879; appliedIndex:8878; }","duration":"156.41588ms","start":"2026-04-03T02:39:40.739499Z","end":"2026-04-03T02:39:40.895915Z","steps":["trace[654585476] 'read index received' (duration: 156.242245ms)","trace[654585476] 'applied index is now lower than readState.Index' (duration: 172.885µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:40.895995Z","caller":"traceutil/trace.go:171","msg":"trace[1529106917] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"157.53236ms","start":"2026-04-03T02:39:40.738429Z","end":"2026-04-03T02:39:40.895961Z","steps":["trace[1529106917] 'process raft request' (duration: 157.385146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:40.896196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.678657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-f9m4d\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-04-03T02:39:40.896251Z","caller":"traceutil/trace.go:171","msg":"trace[722476664] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-f9m4d; range_end:; response_count:1; response_revision:8577; }","duration":"156.798261ms","start":"2026-04-03T02:39:40.739436Z","end":"2026-04-03T02:39:40.896234Z","steps":["trace[722476664] 'agreement among raft nodes before linearized reading' (duration: 156.564814ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:41.07824Z","caller":"traceutil/trace.go:171","msg":"trace[1219815013] linearizableReadLoop","detail":"{readStateIndex:8880; appliedIndex:8879; }","duration":"179.69195ms","start":"2026-04-03T02:39:40.898518Z","end":"2026-04-03T02:39:41.07821Z","steps":["trace[1219815013] 'read index received' (duration: 169.769037ms)","trace[1219815013] 'applied index is now lower than readState.Index' (duration: 9.920913ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:39:41.078408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.861794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:39:41.078455Z","caller":"traceutil/trace.go:171","msg":"trace[456478815] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8577; }","duration":"179.971767ms","start":"2026-04-03T02:39:40.89847Z","end":"2026-04-03T02:39:41.078442Z","steps":["trace[456478815] 'agreement among raft nodes before linearized reading' (duration: 179.871054ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:41.088949Z","caller":"traceutil/trace.go:171","msg":"trace[1057359447] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8578; }","duration":"190.143727ms","start":"2026-04-03T02:39:40.898773Z","end":"2026-04-03T02:39:41.088917Z","steps":["trace[1057359447] 'process raft request' (duration: 189.962792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:41.089022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.495399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-f9m4d\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-04-03T02:39:41.089084Z","caller":"traceutil/trace.go:171","msg":"trace[2066821883] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-f9m4d; range_end:; response_count:1; response_revision:8578; }","duration":"189.603002ms","start":"2026-04-03T02:39:40.899465Z","end":"2026-04-03T02:39:41.089068Z","steps":["trace[2066821883] 'agreement among raft nodes before linearized reading' (duration: 189.413487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:41.089078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.737019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:39:41.089143Z","caller":"traceutil/trace.go:171","msg":"trace[681802991] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8578; }","duration":"128.860812ms","start":"2026-04-03T02:39:40.960268Z","end":"2026-04-03T02:39:41.089129Z","steps":["trace[681802991] 'agreement among raft nodes before linearized reading' (duration: 128.713148ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:41.863942Z","caller":"traceutil/trace.go:171","msg":"trace[1400601108] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"145.717986ms","start":"2026-04-03T02:39:41.718199Z","end":"2026-04-03T02:39:41.863917Z","steps":["trace[1400601108] 'process raft request' (duration: 145.557341ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:42.334949Z","caller":"traceutil/trace.go:171","msg":"trace[822275032] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"101.906292ms","start":"2026-04-03T02:39:42.233012Z","end":"2026-04-03T02:39:42.334919Z","steps":["trace[822275032] 'process raft request' (duration: 101.804718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:42.335092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.684156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:39:42.335134Z","caller":"traceutil/trace.go:171","msg":"trace[1391159606] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:8588; }","duration":"264.781459ms","start":"2026-04-03T02:39:42.070343Z","end":"2026-04-03T02:39:42.335124Z","steps":["trace[1391159606] 'agreement among raft nodes before linearized reading' (duration: 264.677796ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:42.334977Z","caller":"traceutil/trace.go:171","msg":"trace[1831230115] linearizableReadLoop","detail":"{readStateIndex:8890; appliedIndex:8889; }","duration":"264.557392ms","start":"2026-04-03T02:39:42.070397Z","end":"2026-04-03T02:39:42.334954Z","steps":["trace[1831230115] 'read index received' (duration: 199.244026ms)","trace[1831230115] 'applied index is now lower than readState.Index' (duration: 65.311886ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:42.33529Z","caller":"traceutil/trace.go:171","msg":"trace[613520903] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"387.126142ms","start":"2026-04-03T02:39:41.948154Z","end":"2026-04-03T02:39:42.33528Z","steps":["trace[613520903] 'process raft request' (duration: 321.518218ms)","trace[613520903] 'compare' (duration: 64.832152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:39:42.335358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:39:41.948115Z","time spent":"387.193254ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:40:30.35551Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002498.wal"} {"level":"info","ts":"2026-04-03T02:41:36.230142Z","caller":"traceutil/trace.go:171","msg":"trace[869691628] linearizableReadLoop","detail":"{readStateIndex:9956; appliedIndex:9955; }","duration":"356.23363ms","start":"2026-04-03T02:41:35.873885Z","end":"2026-04-03T02:41:36.230119Z","steps":["trace[869691628] 'read index received' (duration: 294.232265ms)","trace[869691628] 'applied index is now lower than readState.Index' (duration: 62.000405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:41:36.230455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.547388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-03T02:41:36.230635Z","caller":"traceutil/trace.go:171","msg":"trace[1370341438] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9625; }","duration":"356.786685ms","start":"2026-04-03T02:41:35.873835Z","end":"2026-04-03T02:41:36.230622Z","steps":["trace[1370341438] 'agreement among raft nodes before linearized reading' (duration: 356.444955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:41:36.230679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:41:35.873815Z","time spent":"356.852207ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-03T02:41:36.231589Z","caller":"traceutil/trace.go:171","msg":"trace[496313558] transaction","detail":"{read_only:false; response_revision:9626; number_of_response:1; }","duration":"336.421575ms","start":"2026-04-03T02:41:35.895116Z","end":"2026-04-03T02:41:36.231537Z","steps":["trace[496313558] 'process raft request' (duration: 334.893102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:41:36.231862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:41:35.895092Z","time spent":"336.649931ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:41:36.286235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.855684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-04-03T02:41:36.286359Z","caller":"traceutil/trace.go:171","msg":"trace[336509379] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:9626; }","duration":"250.023938ms","start":"2026-04-03T02:41:36.036308Z","end":"2026-04-03T02:41:36.286332Z","steps":["trace[336509379] 'agreement among raft nodes before linearized reading' (duration: 195.18447ms)","trace[336509379] 'range keys from in-memory index tree' (duration: 54.55216ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:41:36.499394Z","caller":"traceutil/trace.go:171","msg":"trace[1201724860] linearizableReadLoop","detail":"{readStateIndex:9959; appliedIndex:9958; }","duration":"159.418726ms","start":"2026-04-03T02:41:36.339918Z","end":"2026-04-03T02:41:36.499337Z","steps":["trace[1201724860] 'read index received' (duration: 140.091215ms)","trace[1201724860] 'applied index is now lower than readState.Index' (duration: 19.326051ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:41:36.49955Z","caller":"traceutil/trace.go:171","msg":"trace[2121590750] transaction","detail":"{read_only:false; response_revision:9628; number_of_response:1; }","duration":"205.787201ms","start":"2026-04-03T02:41:36.293744Z","end":"2026-04-03T02:41:36.499531Z","steps":["trace[2121590750] 'process raft request' (duration: 186.340426ms)","trace[2121590750] 'compare' (duration: 19.000483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:41:36.499661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.65139ms","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-03T02:41:36.499726Z","caller":"traceutil/trace.go:171","msg":"trace[1852629532] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9628; }","duration":"111.758913ms","start":"2026-04-03T02:41:36.38795Z","end":"2026-04-03T02:41:36.499708Z","steps":["trace[1852629532] 'agreement among raft nodes before linearized reading' (duration: 111.62618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:41:36.500007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.059625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:41:36.500176Z","caller":"traceutil/trace.go:171","msg":"trace[721054992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9628; }","duration":"160.25718ms","start":"2026-04-03T02:41:36.339867Z","end":"2026-04-03T02:41:36.500124Z","steps":["trace[721054992] 'agreement among raft nodes before linearized reading' (duration: 160.017033ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:41:39.849923Z","caller":"traceutil/trace.go:171","msg":"trace[1899484500] transaction","detail":"{read_only:false; number_of_response:0; response_revision:9663; }","duration":"102.343595ms","start":"2026-04-03T02:41:39.747536Z","end":"2026-04-03T02:41:39.849879Z","steps":["trace[1899484500] 'process raft request' (duration: 96.418952ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:41:40.487039Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-03T02:41:40.492398Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-03T02:41:40.492634Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-03T02:43:57.928211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8257} {"level":"info","ts":"2026-04-03T02:43:58.013444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8257,"took":"83.287997ms","hash":3248010432,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":25100288,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-03T02:43:58.013515Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3248010432,"revision":8257,"compact-revision":6088} {"level":"info","ts":"2026-04-03T02:48:57.934599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10682} {"level":"info","ts":"2026-04-03T02:48:58.016016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10682,"took":"79.167216ms","hash":2433351781,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":20434944,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-03T02:48:58.016077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2433351781,"revision":10682,"compact-revision":8257}