{"level":"warn","ts":"2026-04-12T20:48:17.686348Z","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-12T20:48:17.686436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-12T20:48:17.686618Z","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-12T20:48:17.686636Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-12T20:48:17.686657Z","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-12T20:48:17.687283Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-04-12T20:48:17.687425Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-12T20:48:17.691467Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.730893ms"} {"level":"info","ts":"2026-04-12T20:48:17.69923Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-04-12T20:48:17.699334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:48:17.699359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-04-12T20:48:17.699383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:48:17.699401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-04-12T20:48:17.699468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-04-12T20:48:17.705805Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:48:17.708548Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:48:17.711194Z","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-12T20:48:17.716448Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:48:17.716608Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:48:17.716733Z","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-12T20:48:17.716803Z","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-12T20:48:17.716821Z","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-12T20:48:17.717481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-04-12T20:48:17.717597Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-12T20:48:17.719155Z","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-12T20:48:17.719232Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-12T20:48:17.719276Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-12T20:48:17.719379Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:48:17.719434Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:48:17.900211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:48:17.90035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:48:17.900406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-04-12T20:48:17.900436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:48:17.900451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-12T20:48:17.900473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-04-12T20:48:17.900492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-12T20:48:17.903203Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:48:17.903238Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:48:17.903498Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:48:17.903887Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:48:17.904086Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:48:17.904112Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T20:48:17.907137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-04-12T20:48:17.907649Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:48:17.907724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T20:48:17.907781Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:48:17.907823Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:48:38.608385Z","caller":"traceutil/trace.go:171","msg":"trace[1930886085] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"120.84964ms","start":"2026-04-12T20:48:38.487494Z","end":"2026-04-12T20:48:38.608343Z","steps":["trace[1930886085] 'process raft request' (duration: 120.627364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:38.728286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.073287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:48:38.728473Z","caller":"traceutil/trace.go:171","msg":"trace[560080620] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"115.096422ms","start":"2026-04-12T20:48:38.613346Z","end":"2026-04-12T20:48:38.728443Z","steps":["trace[560080620] 'process raft request' (duration: 10.035177ms)","trace[560080620] 'compare' (duration: 103.889952ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:47.704283Z","caller":"traceutil/trace.go:171","msg":"trace[2085081851] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"122.226849ms","start":"2026-04-12T20:48:47.582024Z","end":"2026-04-12T20:48:47.704251Z","steps":["trace[2085081851] 'process raft request' (duration: 122.025474ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:47.710901Z","caller":"traceutil/trace.go:171","msg":"trace[1282900489] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:523; }","duration":"101.072785ms","start":"2026-04-12T20:48:47.609798Z","end":"2026-04-12T20:48:47.71087Z","steps":["trace[1282900489] 'read index received' (duration: 94.852313ms)","trace[1282900489] 'applied index is now lower than readState.Index' (duration: 6.219702ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:47.711076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.26086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-12T20:48:47.711117Z","caller":"traceutil/trace.go:171","msg":"trace[30506362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"101.374203ms","start":"2026-04-12T20:48:47.609733Z","end":"2026-04-12T20:48:47.711108Z","steps":["trace[30506362] 'agreement among raft nodes before linearized reading' (duration: 101.247499ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:47.711458Z","caller":"traceutil/trace.go:171","msg":"trace[244614766] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"128.777791ms","start":"2026-04-12T20:48:47.582668Z","end":"2026-04-12T20:48:47.711446Z","steps":["trace[244614766] 'process raft request' (duration: 127.524246ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:47.711699Z","caller":"traceutil/trace.go:171","msg":"trace[891578603] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"104.376146ms","start":"2026-04-12T20:48:47.607311Z","end":"2026-04-12T20:48:47.711688Z","steps":["trace[891578603] 'process raft request' (duration: 102.991787ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:48.581665Z","caller":"traceutil/trace.go:171","msg":"trace[1786691955] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"240.665915ms","start":"2026-04-12T20:48:48.340977Z","end":"2026-04-12T20:48:48.581643Z","steps":["trace[1786691955] 'process raft request' (duration: 232.824468ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:48.583206Z","caller":"traceutil/trace.go:171","msg":"trace[1926588814] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"112.054518ms","start":"2026-04-12T20:48:48.471121Z","end":"2026-04-12T20:48:48.583176Z","steps":["trace[1926588814] 'process raft request' (duration: 111.522383ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:54.217905Z","caller":"traceutil/trace.go:171","msg":"trace[768540225] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"159.4933ms","start":"2026-04-12T20:48:54.058369Z","end":"2026-04-12T20:48:54.217863Z","steps":["trace[768540225] 'read index received' (duration: 158.239145ms)","trace[768540225] 'applied index is now lower than readState.Index' (duration: 1.252325ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:54.218006Z","caller":"traceutil/trace.go:171","msg":"trace[1044913537] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"181.142569ms","start":"2026-04-12T20:48:54.036822Z","end":"2026-04-12T20:48:54.217965Z","steps":["trace[1044913537] 'process raft request' (duration: 180.177932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:54.21828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.890611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-12T20:48:54.21834Z","caller":"traceutil/trace.go:171","msg":"trace[943960941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"159.977123ms","start":"2026-04-12T20:48:54.05835Z","end":"2026-04-12T20:48:54.218327Z","steps":["trace[943960941] 'agreement among raft nodes before linearized reading' (duration: 159.732716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:54.218512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.396307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dvbch\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-12T20:48:54.2186Z","caller":"traceutil/trace.go:171","msg":"trace[1884277940] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dvbch; range_end:; response_count:1; response_revision:615; }","duration":"117.544481ms","start":"2026-04-12T20:48:54.101031Z","end":"2026-04-12T20:48:54.218575Z","steps":["trace[1884277940] 'agreement among raft nodes before linearized reading' (duration: 117.226842ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:54.561189Z","caller":"traceutil/trace.go:171","msg":"trace[703616057] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"329.305875ms","start":"2026-04-12T20:48:54.231839Z","end":"2026-04-12T20:48:54.561145Z","steps":["trace[703616057] 'process raft request' (duration: 289.781062ms)","trace[703616057] 'compare' (duration: 39.333098ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:54.561318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:54.231805Z","time spent":"329.445829ms","remote":"127.0.0.1:36750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:48:54.561671Z","caller":"traceutil/trace.go:171","msg":"trace[1288432894] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"280.306861ms","start":"2026-04-12T20:48:54.281354Z","end":"2026-04-12T20:48:54.561661Z","steps":["trace[1288432894] 'process raft request' (duration: 280.25877ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:43.338517Z","caller":"traceutil/trace.go:171","msg":"trace[1878013501] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"128.310748ms","start":"2026-04-12T20:49:43.210174Z","end":"2026-04-12T20:49:43.338485Z","steps":["trace[1878013501] 'read index received' (duration: 128.02315ms)","trace[1878013501] 'applied index is now lower than readState.Index' (duration: 286.147µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:49:43.338696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.509183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:49:43.338736Z","caller":"traceutil/trace.go:171","msg":"trace[20397851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"128.635247ms","start":"2026-04-12T20:49:43.210087Z","end":"2026-04-12T20:49:43.338722Z","steps":["trace[20397851] 'agreement among raft nodes before linearized reading' (duration: 128.541884ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:43.338903Z","caller":"traceutil/trace.go:171","msg":"trace[842845626] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"258.137087ms","start":"2026-04-12T20:49:43.080721Z","end":"2026-04-12T20:49:43.338858Z","steps":["trace[842845626] 'process raft request' (duration: 257.54373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:32.774394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.951196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-12T20:50:32.774525Z","caller":"traceutil/trace.go:171","msg":"trace[965650305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1893; }","duration":"135.161911ms","start":"2026-04-12T20:50:32.639337Z","end":"2026-04-12T20:50:32.774499Z","steps":["trace[965650305] 'range keys from in-memory index tree' (duration: 134.694098ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:32.774685Z","caller":"traceutil/trace.go:171","msg":"trace[2007256109] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"131.369277ms","start":"2026-04-12T20:50:32.643276Z","end":"2026-04-12T20:50:32.774646Z","steps":["trace[2007256109] 'process raft request' (duration: 126.531734ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:32.774656Z","caller":"traceutil/trace.go:171","msg":"trace[1627665660] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"128.363375ms","start":"2026-04-12T20:50:32.646269Z","end":"2026-04-12T20:50:32.774633Z","steps":["trace[1627665660] 'read index received' (duration: 123.551232ms)","trace[1627665660] 'applied index is now lower than readState.Index' (duration: 4.810243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:50:32.774917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.623321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10666"} {"level":"info","ts":"2026-04-12T20:50:32.774988Z","caller":"traceutil/trace.go:171","msg":"trace[760730692] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1894; }","duration":"100.032104ms","start":"2026-04-12T20:50:32.674928Z","end":"2026-04-12T20:50:32.77496Z","steps":["trace[760730692] 'agreement among raft nodes before linearized reading' (duration: 99.983732ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:32.775012Z","caller":"traceutil/trace.go:171","msg":"trace[1920302263] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1894; }","duration":"128.775505ms","start":"2026-04-12T20:50:32.646212Z","end":"2026-04-12T20:50:32.774987Z","steps":["trace[1920302263] 'agreement among raft nodes before linearized reading' (duration: 128.514518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:33.110446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.043015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:50:33.110653Z","caller":"traceutil/trace.go:171","msg":"trace[1388799013] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"325.281945ms","start":"2026-04-12T20:50:32.785353Z","end":"2026-04-12T20:50:33.110635Z","steps":["trace[1388799013] 'process raft request' (duration: 325.190702ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:33.110677Z","caller":"traceutil/trace.go:171","msg":"trace[444160560] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"329.87138ms","start":"2026-04-12T20:50:32.780771Z","end":"2026-04-12T20:50:33.110643Z","steps":["trace[444160560] 'process raft request' (duration: 108.538317ms)","trace[444160560] 'compare' (duration: 220.899241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:50:33.110713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:32.785344Z","time spent":"325.341906ms","remote":"127.0.0.1:36750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10968,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:50:33.11079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:32.780747Z","time spent":"329.983783ms","remote":"127.0.0.1:36852","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-12T20:50:45.534864Z","caller":"traceutil/trace.go:171","msg":"trace[1985129251] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"129.508516ms","start":"2026-04-12T20:50:45.405329Z","end":"2026-04-12T20:50:45.534838Z","steps":["trace[1985129251] 'read index received' (duration: 129.28513ms)","trace[1985129251] 'applied index is now lower than readState.Index' (duration: 222.056µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:45.534915Z","caller":"traceutil/trace.go:171","msg":"trace[1304128924] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"135.799139ms","start":"2026-04-12T20:50:45.399082Z","end":"2026-04-12T20:50:45.534881Z","steps":["trace[1304128924] 'process raft request' (duration: 135.542412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:45.535128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.776153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-12T20:50:45.53524Z","caller":"traceutil/trace.go:171","msg":"trace[1007607587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2000; }","duration":"129.926047ms","start":"2026-04-12T20:50:45.405291Z","end":"2026-04-12T20:50:45.535217Z","steps":["trace[1007607587] 'agreement among raft nodes before linearized reading' (duration: 129.67837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:45.535159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.726611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-qkljx\" ","response":"range_response_count:1 size:5850"} {"level":"info","ts":"2026-04-12T20:50:45.535327Z","caller":"traceutil/trace.go:171","msg":"trace[1271656135] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-qkljx; range_end:; response_count:1; response_revision:2000; }","duration":"109.934037ms","start":"2026-04-12T20:50:45.425378Z","end":"2026-04-12T20:50:45.535312Z","steps":["trace[1271656135] 'agreement among raft nodes before linearized reading' (duration: 109.637169ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:45.690464Z","caller":"traceutil/trace.go:171","msg":"trace[440522075] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"149.67735ms","start":"2026-04-12T20:50:45.540715Z","end":"2026-04-12T20:50:45.690393Z","steps":["trace[440522075] 'process raft request' (duration: 130.79831ms)","trace[440522075] 'compare' (duration: 18.720686ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:45.692857Z","caller":"traceutil/trace.go:171","msg":"trace[1690148379] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"148.764095ms","start":"2026-04-12T20:50:45.544059Z","end":"2026-04-12T20:50:45.692823Z","steps":["trace[1690148379] 'process raft request' (duration: 148.56447ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:18.03125Z","caller":"traceutil/trace.go:171","msg":"trace[1421436593] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"142.608826ms","start":"2026-04-12T20:51:17.888611Z","end":"2026-04-12T20:51:18.03122Z","steps":["trace[1421436593] 'process raft request' (duration: 103.186133ms)","trace[1421436593] 'compare' (duration: 39.30649ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:18.188702Z","caller":"traceutil/trace.go:171","msg":"trace[1563842716] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"128.638265ms","start":"2026-04-12T20:51:18.059734Z","end":"2026-04-12T20:51:18.188373Z","steps":["trace[1563842716] 'process raft request' (duration: 128.48637ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:23.866079Z","caller":"traceutil/trace.go:171","msg":"trace[1366347446] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"129.430835ms","start":"2026-04-12T20:51:23.736612Z","end":"2026-04-12T20:51:23.866043Z","steps":["trace[1366347446] 'process raft request' (duration: 109.674832ms)","trace[1366347446] 'compare' (duration: 19.599568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:24.428287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.349845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:24.428403Z","caller":"traceutil/trace.go:171","msg":"trace[1856910998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2225; }","duration":"219.52165ms","start":"2026-04-12T20:51:24.208856Z","end":"2026-04-12T20:51:24.428377Z","steps":["trace[1856910998] 'range keys from in-memory index tree' (duration: 219.272954ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:24.428852Z","caller":"traceutil/trace.go:171","msg":"trace[1876618714] linearizableReadLoop","detail":"{readStateIndex:2304; appliedIndex:2303; }","duration":"201.784463ms","start":"2026-04-12T20:51:24.22704Z","end":"2026-04-12T20:51:24.428824Z","steps":["trace[1876618714] 'read index received' (duration: 129.985881ms)","trace[1876618714] 'applied index is now lower than readState.Index' (duration: 71.797472ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:24.429018Z","caller":"traceutil/trace.go:171","msg":"trace[595138966] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"218.951284ms","start":"2026-04-12T20:51:24.209956Z","end":"2026-04-12T20:51:24.428907Z","steps":["trace[595138966] 'process raft request' (duration: 147.04015ms)","trace[595138966] 'compare' (duration: 71.496883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:24.429278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.193534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:51:24.429402Z","caller":"traceutil/trace.go:171","msg":"trace[244477625] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2226; }","duration":"202.40292ms","start":"2026-04-12T20:51:24.226974Z","end":"2026-04-12T20:51:24.429377Z","steps":["trace[244477625] 'agreement among raft nodes before linearized reading' (duration: 201.951978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:24.723104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.993997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-12T20:51:24.723218Z","caller":"traceutil/trace.go:171","msg":"trace[597099448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2226; }","duration":"116.173132ms","start":"2026-04-12T20:51:24.607022Z","end":"2026-04-12T20:51:24.723195Z","steps":["trace[597099448] 'range keys from in-memory index tree' (duration: 115.803952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:24.723258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.692255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:51:24.723407Z","caller":"traceutil/trace.go:171","msg":"trace[1073236481] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2226; }","duration":"148.952353ms","start":"2026-04-12T20:51:24.574421Z","end":"2026-04-12T20:51:24.723374Z","steps":["trace[1073236481] 'count revisions from in-memory index tree' (duration: 148.50249ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:52:48.887329Z","caller":"traceutil/trace.go:171","msg":"trace[1386199584] transaction","detail":"{read_only:false; response_revision:2877; number_of_response:1; }","duration":"149.24759ms","start":"2026-04-12T20:52:48.738044Z","end":"2026-04-12T20:52:48.887291Z","steps":["trace[1386199584] 'process raft request' (duration: 82.810049ms)","trace[1386199584] 'compare' (duration: 66.242616ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:52:49.212335Z","caller":"traceutil/trace.go:171","msg":"trace[55539331] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"160.21241ms","start":"2026-04-12T20:52:49.052088Z","end":"2026-04-12T20:52:49.2123Z","steps":["trace[55539331] 'process raft request' (duration: 159.997824ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:45.899968Z","caller":"traceutil/trace.go:171","msg":"trace[399346872] linearizableReadLoop","detail":"{readStateIndex:5099; appliedIndex:5093; }","duration":"115.397171ms","start":"2026-04-12T20:56:45.784535Z","end":"2026-04-12T20:56:45.899932Z","steps":["trace[399346872] 'read index received' (duration: 7.804225ms)","trace[399346872] 'applied index is now lower than readState.Index' (duration: 107.591486ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:56:45.900461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.892045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-12T20:56:45.900516Z","caller":"traceutil/trace.go:171","msg":"trace[346013190] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4917; }","duration":"116.017929ms","start":"2026-04-12T20:56:45.78448Z","end":"2026-04-12T20:56:45.900498Z","steps":["trace[346013190] 'agreement among raft nodes before linearized reading' (duration: 115.753862ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:45.90084Z","caller":"traceutil/trace.go:171","msg":"trace[748708353] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"120.510242ms","start":"2026-04-12T20:56:45.780317Z","end":"2026-04-12T20:56:45.900827Z","steps":["trace[748708353] 'process raft request' (duration: 119.170395ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:45.901093Z","caller":"traceutil/trace.go:171","msg":"trace[1738422292] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"120.43318ms","start":"2026-04-12T20:56:45.780645Z","end":"2026-04-12T20:56:45.901078Z","steps":["trace[1738422292] 'process raft request' (duration: 119.067422ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:45.901305Z","caller":"traceutil/trace.go:171","msg":"trace[458051232] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"119.753091ms","start":"2026-04-12T20:56:45.781543Z","end":"2026-04-12T20:56:45.901296Z","steps":["trace[458051232] 'process raft request' (duration: 118.211338ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:45.901458Z","caller":"traceutil/trace.go:171","msg":"trace[663733718] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"119.849404ms","start":"2026-04-12T20:56:45.781599Z","end":"2026-04-12T20:56:45.901448Z","steps":["trace[663733718] 'process raft request' (duration: 118.188148ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:45.901595Z","caller":"traceutil/trace.go:171","msg":"trace[689041235] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"119.919776ms","start":"2026-04-12T20:56:45.781665Z","end":"2026-04-12T20:56:45.901585Z","steps":["trace[689041235] 'process raft request' (duration: 118.148307ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:45.901924Z","caller":"traceutil/trace.go:171","msg":"trace[1232379405] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"113.094878ms","start":"2026-04-12T20:56:45.788817Z","end":"2026-04-12T20:56:45.901912Z","steps":["trace[1232379405] 'process raft request' (duration: 111.030891ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:45.932484Z","caller":"traceutil/trace.go:171","msg":"trace[1903396522] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"136.334787ms","start":"2026-04-12T20:56:45.79611Z","end":"2026-04-12T20:56:45.932445Z","steps":["trace[1903396522] 'process raft request' (duration: 136.038519ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:50.310505Z","caller":"traceutil/trace.go:171","msg":"trace[549040337] linearizableReadLoop","detail":"{readStateIndex:5260; appliedIndex:5259; }","duration":"102.25231ms","start":"2026-04-12T20:56:50.208207Z","end":"2026-04-12T20:56:50.31046Z","steps":["trace[549040337] 'read index received' (duration: 12.923155ms)","trace[549040337] 'applied index is now lower than readState.Index' (duration: 89.327115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:56:50.310747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.519718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:56:50.310895Z","caller":"traceutil/trace.go:171","msg":"trace[888467672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5072; }","duration":"102.744853ms","start":"2026-04-12T20:56:50.208136Z","end":"2026-04-12T20:56:50.310881Z","steps":["trace[888467672] 'agreement among raft nodes before linearized reading' (duration: 102.517207ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:56:50.310705Z","caller":"traceutil/trace.go:171","msg":"trace[1093706124] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"198.897106ms","start":"2026-04-12T20:56:50.111766Z","end":"2026-04-12T20:56:50.310664Z","steps":["trace[1093706124] 'process raft request' (duration: 109.429858ms)","trace[1093706124] 'compare' (duration: 88.931274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:56:51.854889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.97475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:56:51.855111Z","caller":"traceutil/trace.go:171","msg":"trace[429096843] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"145.216981ms","start":"2026-04-12T20:56:51.70986Z","end":"2026-04-12T20:56:51.855077Z","steps":["trace[429096843] 'process raft request' (duration: 29.920682ms)","trace[429096843] 'compare' (duration: 114.786475ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:57:11.494929Z","caller":"traceutil/trace.go:171","msg":"trace[1290948791] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"108.819191ms","start":"2026-04-12T20:57:11.386068Z","end":"2026-04-12T20:57:11.494888Z","steps":["trace[1290948791] 'process raft request' (duration: 23.350852ms)","trace[1290948791] 'compare' (duration: 85.191711ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:57:11.699227Z","caller":"traceutil/trace.go:171","msg":"trace[380446610] linearizableReadLoop","detail":"{readStateIndex:5687; appliedIndex:5686; }","duration":"141.18642ms","start":"2026-04-12T20:57:11.557997Z","end":"2026-04-12T20:57:11.699184Z","steps":["trace[380446610] 'read index received' (duration: 73.152431ms)","trace[380446610] 'applied index is now lower than readState.Index' (duration: 68.032379ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:57:11.699286Z","caller":"traceutil/trace.go:171","msg":"trace[1319228900] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"145.794337ms","start":"2026-04-12T20:57:11.55344Z","end":"2026-04-12T20:57:11.699234Z","steps":["trace[1319228900] 'process raft request' (duration: 77.783918ms)","trace[1319228900] 'compare' (duration: 67.752201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:57:11.699589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.56252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54eebae2-ea87-4a10-8322-626121da50be\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-12T20:57:11.699648Z","caller":"traceutil/trace.go:171","msg":"trace[318267039] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54eebae2-ea87-4a10-8322-626121da50be; range_end:; response_count:1; response_revision:5484; }","duration":"141.716474ms","start":"2026-04-12T20:57:11.557915Z","end":"2026-04-12T20:57:11.699632Z","steps":["trace[318267039] 'agreement among raft nodes before linearized reading' (duration: 141.432417ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:57:11.957972Z","caller":"traceutil/trace.go:171","msg":"trace[616488788] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"159.533694ms","start":"2026-04-12T20:57:11.798406Z","end":"2026-04-12T20:57:11.95794Z","steps":["trace[616488788] 'process raft request' (duration: 159.35744ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:57:11.964383Z","caller":"traceutil/trace.go:171","msg":"trace[1912374154] linearizableReadLoop","detail":"{readStateIndex:5689; appliedIndex:5687; }","duration":"142.962159ms","start":"2026-04-12T20:57:11.821394Z","end":"2026-04-12T20:57:11.964356Z","steps":["trace[1912374154] 'read index received' (duration: 136.363127ms)","trace[1912374154] 'applied index is now lower than readState.Index' (duration: 6.597792ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:57:11.964421Z","caller":"traceutil/trace.go:171","msg":"trace[1274161033] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"163.670178ms","start":"2026-04-12T20:57:11.800728Z","end":"2026-04-12T20:57:11.964398Z","steps":["trace[1274161033] 'process raft request' (duration: 163.485313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:57:11.964732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.300688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a5b789cbf7271b\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-04-12T20:57:11.964837Z","caller":"traceutil/trace.go:171","msg":"trace[166657967] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a5b789cbf7271b; range_end:; response_count:1; response_revision:5486; }","duration":"143.472674ms","start":"2026-04-12T20:57:11.821339Z","end":"2026-04-12T20:57:11.964812Z","steps":["trace[166657967] 'agreement among raft nodes before linearized reading' (duration: 143.128354ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:57:12.087238Z","caller":"traceutil/trace.go:171","msg":"trace[1749208180] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"112.43852ms","start":"2026-04-12T20:57:11.974774Z","end":"2026-04-12T20:57:12.087213Z","steps":["trace[1749208180] 'process raft request' (duration: 111.644308ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:58:18.273645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3038} {"level":"info","ts":"2026-04-12T20:58:18.359624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3038,"took":"83.893867ms","hash":2752488344,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-12T20:58:18.359712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2752488344,"revision":3038,"compact-revision":-1} {"level":"info","ts":"2026-04-12T21:03:18.280244Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-04-12T21:03:18.373308Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"90.84735ms","hash":797043939,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":23805952,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-12T21:03:18.373408Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":797043939,"revision":6103,"compact-revision":3038} {"level":"warn","ts":"2026-04-12T21:03:21.785361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.887681ms","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-12T21:03:21.785465Z","caller":"traceutil/trace.go:171","msg":"trace[177790455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8340; }","duration":"276.044305ms","start":"2026-04-12T21:03:21.509395Z","end":"2026-04-12T21:03:21.78544Z","steps":["trace[177790455] 'range keys from in-memory index tree' (duration: 275.630823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T21:03:21.785512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.467073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T21:03:21.785629Z","caller":"traceutil/trace.go:171","msg":"trace[295132478] transaction","detail":"{read_only:false; response_revision:8341; number_of_response:1; }","duration":"236.006668ms","start":"2026-04-12T21:03:21.549603Z","end":"2026-04-12T21:03:21.785609Z","steps":["trace[295132478] 'process raft request' (duration: 100.33556ms)","trace[295132478] 'compare' (duration: 135.251346ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T21:03:21.794314Z","caller":"traceutil/trace.go:171","msg":"trace[1768789156] transaction","detail":"{read_only:false; response_revision:8342; number_of_response:1; }","duration":"208.894095ms","start":"2026-04-12T21:03:21.585396Z","end":"2026-04-12T21:03:21.79429Z","steps":["trace[1768789156] 'process raft request' (duration: 208.752971ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:03:33.254517Z","caller":"traceutil/trace.go:171","msg":"trace[282436358] transaction","detail":"{read_only:false; response_revision:8402; number_of_response:1; }","duration":"203.775864ms","start":"2026-04-12T21:03:33.050716Z","end":"2026-04-12T21:03:33.254492Z","steps":["trace[282436358] 'process raft request' (duration: 203.62548ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:03:33.292659Z","caller":"traceutil/trace.go:171","msg":"trace[2095119745] transaction","detail":"{read_only:false; response_revision:8403; number_of_response:1; }","duration":"238.048023ms","start":"2026-04-12T21:03:33.054582Z","end":"2026-04-12T21:03:33.29263Z","steps":["trace[2095119745] 'process raft request' (duration: 237.821477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T21:03:36.308406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.723892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T21:03:36.308531Z","caller":"traceutil/trace.go:171","msg":"trace[1338125727] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8421; }","duration":"107.946189ms","start":"2026-04-12T21:03:36.200552Z","end":"2026-04-12T21:03:36.308499Z","steps":["trace[1338125727] 'count revisions from in-memory index tree' (duration: 107.586708ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:04:03.131579Z","caller":"traceutil/trace.go:171","msg":"trace[875781692] linearizableReadLoop","detail":"{readStateIndex:8918; appliedIndex:8917; }","duration":"199.733763ms","start":"2026-04-12T21:04:02.931807Z","end":"2026-04-12T21:04:03.131541Z","steps":["trace[875781692] 'read index received' (duration: 199.466636ms)","trace[875781692] 'applied index is now lower than readState.Index' (duration: 265.997µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T21:04:03.132005Z","caller":"traceutil/trace.go:171","msg":"trace[1265495414] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"221.089747ms","start":"2026-04-12T21:04:02.910883Z","end":"2026-04-12T21:04:03.131973Z","steps":["trace[1265495414] 'process raft request' (duration: 220.479631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T21:04:03.132396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.569617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-12T21:04:03.132453Z","caller":"traceutil/trace.go:171","msg":"trace[265025412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8615; }","duration":"200.69205ms","start":"2026-04-12T21:04:02.931746Z","end":"2026-04-12T21:04:03.132438Z","steps":["trace[265025412] 'agreement among raft nodes before linearized reading' (duration: 200.507635ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:04:03.238002Z","caller":"traceutil/trace.go:171","msg":"trace[1861025627] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"101.269575ms","start":"2026-04-12T21:04:03.136694Z","end":"2026-04-12T21:04:03.237963Z","steps":["trace[1861025627] 'process raft request' (duration: 101.06192ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:04:07.417763Z","caller":"traceutil/trace.go:171","msg":"trace[330899323] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"159.147426ms","start":"2026-04-12T21:04:07.258588Z","end":"2026-04-12T21:04:07.417736Z","steps":["trace[330899323] 'process raft request' (duration: 158.964861ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:04:15.77243Z","caller":"traceutil/trace.go:171","msg":"trace[790793771] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"105.84606ms","start":"2026-04-12T21:04:15.666551Z","end":"2026-04-12T21:04:15.772397Z","steps":["trace[790793771] 'process raft request' (duration: 105.584323ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:04:15.878794Z","caller":"traceutil/trace.go:171","msg":"trace[148209965] linearizableReadLoop","detail":"{readStateIndex:9031; appliedIndex:9030; }","duration":"100.879155ms","start":"2026-04-12T21:04:15.777885Z","end":"2026-04-12T21:04:15.878764Z","steps":["trace[148209965] 'read index received' (duration: 87.16881ms)","trace[148209965] 'applied index is now lower than readState.Index' (duration: 13.709235ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T21:04:15.879009Z","caller":"traceutil/trace.go:171","msg":"trace[1827275400] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"119.317398ms","start":"2026-04-12T21:04:15.759676Z","end":"2026-04-12T21:04:15.878993Z","steps":["trace[1827275400] 'process raft request' (duration: 105.512971ms)","trace[1827275400] 'compare' (duration: 13.426277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T21:04:15.879681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.771009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228699"} {"level":"info","ts":"2026-04-12T21:04:15.879798Z","caller":"traceutil/trace.go:171","msg":"trace[663154037] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8723; }","duration":"101.961324ms","start":"2026-04-12T21:04:15.777814Z","end":"2026-04-12T21:04:15.879775Z","steps":["trace[663154037] 'agreement among raft nodes before linearized reading' (duration: 101.349657ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:04:16.017938Z","caller":"traceutil/trace.go:171","msg":"trace[1894120637] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"127.947144ms","start":"2026-04-12T21:04:15.889953Z","end":"2026-04-12T21:04:16.0179Z","steps":["trace[1894120637] 'process raft request' (duration: 108.954175ms)","trace[1894120637] 'compare' (duration: 18.799353ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T21:04:16.612773Z","caller":"traceutil/trace.go:171","msg":"trace[725407184] linearizableReadLoop","detail":"{readStateIndex:9040; appliedIndex:9039; }","duration":"144.389172ms","start":"2026-04-12T21:04:16.46835Z","end":"2026-04-12T21:04:16.612739Z","steps":["trace[725407184] 'read index received' (duration: 144.223128ms)","trace[725407184] 'applied index is now lower than readState.Index' (duration: 164.864µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T21:04:16.612797Z","caller":"traceutil/trace.go:171","msg":"trace[393404691] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"146.11539ms","start":"2026-04-12T21:04:16.466646Z","end":"2026-04-12T21:04:16.612761Z","steps":["trace[393404691] 'process raft request' (duration: 145.868673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T21:04:16.613108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.736362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-12T21:04:16.613206Z","caller":"traceutil/trace.go:171","msg":"trace[769656018] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8731; }","duration":"144.897927ms","start":"2026-04-12T21:04:16.468283Z","end":"2026-04-12T21:04:16.613181Z","steps":["trace[769656018] 'agreement among raft nodes before linearized reading' (duration: 144.616389ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:04:16.909176Z","caller":"traceutil/trace.go:171","msg":"trace[2056143443] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"433.99786ms","start":"2026-04-12T21:04:16.475112Z","end":"2026-04-12T21:04:16.90911Z","steps":["trace[2056143443] 'process raft request' (duration: 387.455689ms)","trace[2056143443] 'compare' (duration: 46.172201ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T21:04:16.909215Z","caller":"traceutil/trace.go:171","msg":"trace[458016241] linearizableReadLoop","detail":"{readStateIndex:9041; appliedIndex:9040; }","duration":"289.867705ms","start":"2026-04-12T21:04:16.619278Z","end":"2026-04-12T21:04:16.909146Z","steps":["trace[458016241] 'read index received' (duration: 243.309254ms)","trace[458016241] 'applied index is now lower than readState.Index' (duration: 46.557411ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T21:04:16.909397Z","caller":"traceutil/trace.go:171","msg":"trace[1380931436] transaction","detail":"{read_only:false; response_revision:8733; number_of_response:1; }","duration":"289.426833ms","start":"2026-04-12T21:04:16.619945Z","end":"2026-04-12T21:04:16.909372Z","steps":["trace[1380931436] 'process raft request' (duration: 289.146476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T21:04:16.90938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T21:04:16.475079Z","time spent":"434.186475ms","remote":"127.0.0.1:36824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T21:04:16.909893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.585225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-04-12T21:04:16.910002Z","caller":"traceutil/trace.go:171","msg":"trace[1783828920] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8733; }","duration":"290.74701ms","start":"2026-04-12T21:04:16.619222Z","end":"2026-04-12T21:04:16.909969Z","steps":["trace[1783828920] 'agreement among raft nodes before linearized reading' (duration: 290.104402ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:04:51.382288Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a8.wal"} {"level":"info","ts":"2026-04-12T21:05:30.621759Z","caller":"traceutil/trace.go:171","msg":"trace[605715520] linearizableReadLoop","detail":"{readStateIndex:9790; appliedIndex:9789; }","duration":"242.887963ms","start":"2026-04-12T21:05:30.378843Z","end":"2026-04-12T21:05:30.621731Z","steps":["trace[605715520] 'read index received' (duration: 242.78874ms)","trace[605715520] 'applied index is now lower than readState.Index' (duration: 97.913µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T21:05:30.62188Z","caller":"traceutil/trace.go:171","msg":"trace[2140625412] transaction","detail":"{read_only:false; response_revision:9464; number_of_response:1; }","duration":"248.134376ms","start":"2026-04-12T21:05:30.373709Z","end":"2026-04-12T21:05:30.621843Z","steps":["trace[2140625412] 'process raft request' (duration: 247.837978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T21:05:30.622042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.17329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-svkng\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-04-12T21:05:30.622112Z","caller":"traceutil/trace.go:171","msg":"trace[2074411069] range","detail":"{range_begin:/registry/pods/openstack/keepalived-svkng; range_end:; response_count:1; response_revision:9464; }","duration":"243.298413ms","start":"2026-04-12T21:05:30.378789Z","end":"2026-04-12T21:05:30.622087Z","steps":["trace[2074411069] 'agreement among raft nodes before linearized reading' (duration: 243.087738ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:05:30.677341Z","caller":"traceutil/trace.go:171","msg":"trace[629624260] transaction","detail":"{read_only:false; response_revision:9466; number_of_response:1; }","duration":"141.941616ms","start":"2026-04-12T21:05:30.535365Z","end":"2026-04-12T21:05:30.677307Z","steps":["trace[629624260] 'process raft request' (duration: 141.841843ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:05:30.677344Z","caller":"traceutil/trace.go:171","msg":"trace[851532155] transaction","detail":"{read_only:false; response_revision:9465; number_of_response:1; }","duration":"141.985107ms","start":"2026-04-12T21:05:30.535318Z","end":"2026-04-12T21:05:30.677304Z","steps":["trace[851532155] 'process raft request' (duration: 141.632897ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:05:30.677556Z","caller":"traceutil/trace.go:171","msg":"trace[321574424] transaction","detail":"{read_only:false; response_revision:9467; number_of_response:1; }","duration":"125.300202ms","start":"2026-04-12T21:05:30.552217Z","end":"2026-04-12T21:05:30.677517Z","steps":["trace[321574424] 'process raft request' (duration: 125.042635ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T21:05:58.030896Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-12T21:05:58.036563Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-12T21:05:58.036699Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-12T21:08:18.287014Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8326} {"level":"info","ts":"2026-04-12T21:08:18.37173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8326,"took":"81.816847ms","hash":999676262,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":25022464,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-12T21:08:18.371805Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":999676262,"revision":8326,"compact-revision":6103} {"level":"info","ts":"2026-04-12T21:13:18.293796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10549} {"level":"info","ts":"2026-04-12T21:13:18.372298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10549,"took":"75.220056ms","hash":3425696095,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":20992000,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-12T21:13:18.372396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3425696095,"revision":10549,"compact-revision":8326}