{"level":"warn","ts":"2026-03-24T14:54:41.709314Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-24T14:54:41.709432Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.100: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.19.213.100:2380","--initial-cluster=instance=https://199.19.213.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.100: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-03-24T14:54:41.709666Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-24T14:54:41.709684Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-03-24T14:54:41.70971Z","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-03-24T14:54:41.710465Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"]} {"level":"info","ts":"2026-03-24T14:54:41.710631Z","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.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.100: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-03-24T14:54:41.714474Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.548868ms"} {"level":"info","ts":"2026-03-24T14:54:41.72167Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"79e65b4197a2a1a","cluster-id":"bba89cbb2a6fc22a"} {"level":"info","ts":"2026-03-24T14:54:41.721755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=()"} {"level":"info","ts":"2026-03-24T14:54:41.72178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 0"} {"level":"info","ts":"2026-03-24T14:54:41.721796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 79e65b4197a2a1a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-24T14:54:41.721805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 1"} {"level":"info","ts":"2026-03-24T14:54:41.721854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"warn","ts":"2026-03-24T14:54:41.726683Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-24T14:54:41.728214Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-24T14:54:41.729587Z","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-03-24T14:54:41.732187Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"79e65b4197a2a1a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-24T14:54:41.732395Z","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-03-24T14:54:41.732501Z","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-03-24T14:54:41.732519Z","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-03-24T14:54:41.732498Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"79e65b4197a2a1a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-24T14:54:41.733975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"info","ts":"2026-03-24T14:54:41.73447Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","added-peer-id":"79e65b4197a2a1a","added-peer-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-03-24T14:54:41.738358Z","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-03-24T14:54:41.738701Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"79e65b4197a2a1a","initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-24T14:54:41.738745Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-24T14:54:41.739049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-03-24T14:54:41.739108Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-03-24T14:54:42.322493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a is starting a new election at term 1"} {"level":"info","ts":"2026-03-24T14:54:42.322593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-24T14:54:42.322646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgPreVoteResp from 79e65b4197a2a1a at term 1"} {"level":"info","ts":"2026-03-24T14:54:42.322671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became candidate at term 2"} {"level":"info","ts":"2026-03-24T14:54:42.322684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgVoteResp from 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-03-24T14:54:42.322709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became leader at term 2"} {"level":"info","ts":"2026-03-24T14:54:42.322724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 79e65b4197a2a1a elected leader 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-03-24T14:54:42.324082Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:54:42.324901Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"79e65b4197a2a1a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.100:2379]}","request-path":"/0/members/79e65b4197a2a1a/attributes","cluster-id":"bba89cbb2a6fc22a","publish-timeout":"7s"} {"level":"info","ts":"2026-03-24T14:54:42.324887Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:54:42.3251Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:54:42.325225Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:54:42.32543Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:54:42.325518Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:54:42.328752Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-24T14:54:42.333303Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.100:2379"} {"level":"info","ts":"2026-03-24T14:54:42.33928Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-24T14:54:42.339365Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-24T14:54:47.17532Z","caller":"traceutil/trace.go:171","msg":"trace[1629493286] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"191.208469ms","start":"2026-03-24T14:54:46.984082Z","end":"2026-03-24T14:54:47.175291Z","steps":["trace[1629493286] 'read index received' (duration: 189.818773ms)","trace[1629493286] 'applied index is now lower than readState.Index' (duration: 1.388536ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:54:47.175369Z","caller":"traceutil/trace.go:171","msg":"trace[532624031] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"191.292541ms","start":"2026-03-24T14:54:46.98404Z","end":"2026-03-24T14:54:47.175332Z","steps":["trace[532624031] 'process raft request' (duration: 189.677111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:54:47.175675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.532015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-24T14:54:47.175768Z","caller":"traceutil/trace.go:171","msg":"trace[1866340218] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:247; }","duration":"191.708629ms","start":"2026-03-24T14:54:46.984042Z","end":"2026-03-24T14:54:47.17575Z","steps":["trace[1866340218] 'agreement among raft nodes before linearized reading' (duration: 191.409553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:54:47.179546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.658656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-03-24T14:54:47.179647Z","caller":"traceutil/trace.go:171","msg":"trace[1065597167] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:252; }","duration":"193.815799ms","start":"2026-03-24T14:54:46.985807Z","end":"2026-03-24T14:54:47.179622Z","steps":["trace[1065597167] 'agreement among raft nodes before linearized reading' (duration: 193.621125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:54:47.180071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.303951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:54:47.180115Z","caller":"traceutil/trace.go:171","msg":"trace[1238876609] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:252; }","duration":"191.361092ms","start":"2026-03-24T14:54:46.98874Z","end":"2026-03-24T14:54:47.180101Z","steps":["trace[1238876609] 'agreement among raft nodes before linearized reading' (duration: 191.301311ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:54:53.127875Z","caller":"traceutil/trace.go:171","msg":"trace[1641159588] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"103.440935ms","start":"2026-03-24T14:54:53.024409Z","end":"2026-03-24T14:54:53.12785Z","steps":["trace[1641159588] 'process raft request' (duration: 103.308843ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:55:18.047459Z","caller":"traceutil/trace.go:171","msg":"trace[1459703435] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"189.813003ms","start":"2026-03-24T14:55:17.857614Z","end":"2026-03-24T14:55:18.047427Z","steps":["trace[1459703435] 'read index received' (duration: 189.606569ms)","trace[1459703435] 'applied index is now lower than readState.Index' (duration: 204.884µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:55:18.047599Z","caller":"traceutil/trace.go:171","msg":"trace[1073524687] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"195.060864ms","start":"2026-03-24T14:55:17.852489Z","end":"2026-03-24T14:55:18.04755Z","steps":["trace[1073524687] 'process raft request' (duration: 194.734898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:55:18.047783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.814554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-24T14:55:18.04784Z","caller":"traceutil/trace.go:171","msg":"trace[276865453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"189.915315ms","start":"2026-03-24T14:55:17.857907Z","end":"2026-03-24T14:55:18.047822Z","steps":["trace[276865453] 'agreement among raft nodes before linearized reading' (duration: 189.733041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:55:18.047828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.16947ms","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-03-24T14:55:18.04794Z","caller":"traceutil/trace.go:171","msg":"trace[597945984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"190.327813ms","start":"2026-03-24T14:55:17.857588Z","end":"2026-03-24T14:55:18.047916Z","steps":["trace[597945984] 'agreement among raft nodes before linearized reading' (duration: 190.022307ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:55:18.226755Z","caller":"traceutil/trace.go:171","msg":"trace[1312925697] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:531; }","duration":"141.961105ms","start":"2026-03-24T14:55:18.084774Z","end":"2026-03-24T14:55:18.226735Z","steps":["trace[1312925697] 'read index received' (duration: 96.88256ms)","trace[1312925697] 'applied index is now lower than readState.Index' (duration: 45.077725ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:55:18.226854Z","caller":"traceutil/trace.go:171","msg":"trace[1211367369] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"174.776895ms","start":"2026-03-24T14:55:18.052017Z","end":"2026-03-24T14:55:18.226794Z","steps":["trace[1211367369] 'process raft request' (duration: 129.66656ms)","trace[1211367369] 'compare' (duration: 44.902571ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:55:18.226931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.134698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x5wn2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-24T14:55:18.226926Z","caller":"traceutil/trace.go:171","msg":"trace[1325808051] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"174.255095ms","start":"2026-03-24T14:55:18.052634Z","end":"2026-03-24T14:55:18.226889Z","steps":["trace[1325808051] 'process raft request' (duration: 174.068971ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:55:18.22697Z","caller":"traceutil/trace.go:171","msg":"trace[351066882] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x5wn2; range_end:; response_count:1; response_revision:514; }","duration":"142.23417ms","start":"2026-03-24T14:55:18.084726Z","end":"2026-03-24T14:55:18.22696Z","steps":["trace[351066882] 'agreement among raft nodes before linearized reading' (duration: 142.087968ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:55:18.473011Z","caller":"traceutil/trace.go:171","msg":"trace[28574404] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"232.422621ms","start":"2026-03-24T14:55:18.240558Z","end":"2026-03-24T14:55:18.472981Z","steps":["trace[28574404] 'process raft request' (duration: 225.723042ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:55:19.038915Z","caller":"traceutil/trace.go:171","msg":"trace[2124130067] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"222.392079ms","start":"2026-03-24T14:55:18.816479Z","end":"2026-03-24T14:55:19.038871Z","steps":["trace[2124130067] 'process raft request' (duration: 222.183485ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:55:20.328093Z","caller":"traceutil/trace.go:171","msg":"trace[1121488396] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"106.472033ms","start":"2026-03-24T14:55:20.221591Z","end":"2026-03-24T14:55:20.328063Z","steps":["trace[1121488396] 'read index received' (duration: 89.556598ms)","trace[1121488396] 'applied index is now lower than readState.Index' (duration: 16.914045ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:55:20.328261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.644527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:55:20.32831Z","caller":"traceutil/trace.go:171","msg":"trace[859664184] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:642; }","duration":"106.735568ms","start":"2026-03-24T14:55:20.221559Z","end":"2026-03-24T14:55:20.328295Z","steps":["trace[859664184] 'agreement among raft nodes before linearized reading' (duration: 106.642006ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:55:20.328303Z","caller":"traceutil/trace.go:171","msg":"trace[1157201129] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"114.964226ms","start":"2026-03-24T14:55:20.213271Z","end":"2026-03-24T14:55:20.328236Z","steps":["trace[1157201129] 'process raft request' (duration: 97.772416ms)","trace[1157201129] 'compare' (duration: 16.898525ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:55:20.330272Z","caller":"traceutil/trace.go:171","msg":"trace[2071209161] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"106.069496ms","start":"2026-03-24T14:55:20.224159Z","end":"2026-03-24T14:55:20.330229Z","steps":["trace[2071209161] 'process raft request' (duration: 105.628266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:55:20.339326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.40434ms","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-03-24T14:55:20.339399Z","caller":"traceutil/trace.go:171","msg":"trace[321789942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"108.549513ms","start":"2026-03-24T14:55:20.23083Z","end":"2026-03-24T14:55:20.33938Z","steps":["trace[321789942] 'agreement among raft nodes before linearized reading' (duration: 108.167176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:55:20.339371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.721399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:55:20.339399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.709979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:55:20.339463Z","caller":"traceutil/trace.go:171","msg":"trace[89743440] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:645; }","duration":"117.838801ms","start":"2026-03-24T14:55:20.221604Z","end":"2026-03-24T14:55:20.339443Z","steps":["trace[89743440] 'agreement among raft nodes before linearized reading' (duration: 117.380103ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:55:20.33949Z","caller":"traceutil/trace.go:171","msg":"trace[807510414] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:645; }","duration":"117.839182ms","start":"2026-03-24T14:55:20.221625Z","end":"2026-03-24T14:55:20.339464Z","steps":["trace[807510414] 'agreement among raft nodes before linearized reading' (duration: 117.384613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:55:20.339411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.623375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-24T14:55:20.340192Z","caller":"traceutil/trace.go:171","msg":"trace[894432319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"109.4255ms","start":"2026-03-24T14:55:20.230731Z","end":"2026-03-24T14:55:20.340156Z","steps":["trace[894432319] 'agreement among raft nodes before linearized reading' (duration: 108.287318ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:56:25.512744Z","caller":"traceutil/trace.go:171","msg":"trace[1820744047] linearizableReadLoop","detail":"{readStateIndex:1591; appliedIndex:1590; }","duration":"157.878866ms","start":"2026-03-24T14:56:25.354836Z","end":"2026-03-24T14:56:25.512715Z","steps":["trace[1820744047] 'read index received' (duration: 157.738583ms)","trace[1820744047] 'applied index is now lower than readState.Index' (duration: 138.473µs)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:56:25.513141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.525628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-de6d7c69-8120-48d7-a1fa-b33bf39b4122\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-24T14:56:25.513224Z","caller":"traceutil/trace.go:171","msg":"trace[1997239338] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-de6d7c69-8120-48d7-a1fa-b33bf39b4122; range_end:; response_count:1; response_revision:1530; }","duration":"157.670271ms","start":"2026-03-24T14:56:25.355539Z","end":"2026-03-24T14:56:25.51321Z","steps":["trace[1997239338] 'agreement among raft nodes before linearized reading' (duration: 157.428606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:56:25.513631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.560238ms","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-03-24T14:56:25.51375Z","caller":"traceutil/trace.go:171","msg":"trace[925503560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1530; }","duration":"159.001156ms","start":"2026-03-24T14:56:25.354722Z","end":"2026-03-24T14:56:25.513723Z","steps":["trace[925503560] 'agreement among raft nodes before linearized reading' (duration: 158.11128ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:56:39.847098Z","caller":"traceutil/trace.go:171","msg":"trace[400404027] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"180.480192ms","start":"2026-03-24T14:56:39.666588Z","end":"2026-03-24T14:56:39.847068Z","steps":["trace[400404027] 'process raft request' (duration: 87.822832ms)","trace[400404027] 'compare' (duration: 92.503307ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:58:41.812045Z","caller":"traceutil/trace.go:171","msg":"trace[475864799] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"125.763921ms","start":"2026-03-24T14:58:41.686239Z","end":"2026-03-24T14:58:41.812003Z","steps":["trace[475864799] 'process raft request' (duration: 125.531416ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:58:42.149312Z","caller":"traceutil/trace.go:171","msg":"trace[1721982133] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"148.946566ms","start":"2026-03-24T14:58:41.999941Z","end":"2026-03-24T14:58:42.148887Z","steps":["trace[1721982133] 'process raft request' (duration: 74.421277ms)","trace[1721982133] 'compare' (duration: 74.333815ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T15:01:07.887138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.193838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-24T15:01:07.887227Z","caller":"traceutil/trace.go:171","msg":"trace[1954991113] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3346; }","duration":"156.328571ms","start":"2026-03-24T15:01:07.73088Z","end":"2026-03-24T15:01:07.887209Z","steps":["trace[1954991113] 'range keys from in-memory index tree' (duration: 156.023755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:01:07.887428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.475124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-frbqd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-24T15:01:07.887526Z","caller":"traceutil/trace.go:171","msg":"trace[846241984] range","detail":"{range_begin:/registry/pods/openstack/keepalived-frbqd; range_end:; response_count:1; response_revision:3346; }","duration":"164.576766ms","start":"2026-03-24T15:01:07.722926Z","end":"2026-03-24T15:01:07.887503Z","steps":["trace[846241984] 'range keys from in-memory index tree' (duration: 164.326441ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:48.212588Z","caller":"traceutil/trace.go:171","msg":"trace[1720666493] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"166.858748ms","start":"2026-03-24T15:02:48.045701Z","end":"2026-03-24T15:02:48.21256Z","steps":["trace[1720666493] 'process raft request' (duration: 128.319079ms)","trace[1720666493] 'compare' (duration: 38.363486ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:02:48.513596Z","caller":"traceutil/trace.go:171","msg":"trace[931888756] linearizableReadLoop","detail":"{readStateIndex:4751; appliedIndex:4750; }","duration":"102.725637ms","start":"2026-03-24T15:02:48.410844Z","end":"2026-03-24T15:02:48.513569Z","steps":["trace[931888756] 'read index received' (duration: 102.410591ms)","trace[931888756] 'applied index is now lower than readState.Index' (duration: 313.766µs)"],"step_count":2} {"level":"warn","ts":"2026-03-24T15:02:48.513778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.888621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T15:02:48.513886Z","caller":"traceutil/trace.go:171","msg":"trace[1854923229] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4569; }","duration":"103.108316ms","start":"2026-03-24T15:02:48.410759Z","end":"2026-03-24T15:02:48.513868Z","steps":["trace[1854923229] 'agreement among raft nodes before linearized reading' (duration: 102.899142ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:48.514306Z","caller":"traceutil/trace.go:171","msg":"trace[628696032] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"113.230327ms","start":"2026-03-24T15:02:48.400384Z","end":"2026-03-24T15:02:48.513615Z","steps":["trace[628696032] 'process raft request' (duration: 112.946852ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:48.66351Z","caller":"traceutil/trace.go:171","msg":"trace[1800001023] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"143.589204ms","start":"2026-03-24T15:02:48.519877Z","end":"2026-03-24T15:02:48.663467Z","steps":["trace[1800001023] 'process raft request' (duration: 140.986792ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:48.663584Z","caller":"traceutil/trace.go:171","msg":"trace[537201783] linearizableReadLoop","detail":"{readStateIndex:4753; appliedIndex:4751; }","duration":"143.201186ms","start":"2026-03-24T15:02:48.52035Z","end":"2026-03-24T15:02:48.663551Z","steps":["trace[537201783] 'read index received' (duration: 17.977828ms)","trace[537201783] 'applied index is now lower than readState.Index' (duration: 125.222068ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:02:48.663678Z","caller":"traceutil/trace.go:171","msg":"trace[402902907] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"141.531282ms","start":"2026-03-24T15:02:48.522103Z","end":"2026-03-24T15:02:48.663635Z","steps":["trace[402902907] 'process raft request' (duration: 141.277628ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:48.663751Z","caller":"traceutil/trace.go:171","msg":"trace[351476734] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"140.303938ms","start":"2026-03-24T15:02:48.523385Z","end":"2026-03-24T15:02:48.663689Z","steps":["trace[351476734] 'process raft request' (duration: 140.092993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:48.663998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.618684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62907"} {"level":"info","ts":"2026-03-24T15:02:48.66408Z","caller":"traceutil/trace.go:171","msg":"trace[856803666] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4573; }","duration":"143.770657ms","start":"2026-03-24T15:02:48.520292Z","end":"2026-03-24T15:02:48.664063Z","steps":["trace[856803666] 'agreement among raft nodes before linearized reading' (duration: 143.449701ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:57.524228Z","caller":"traceutil/trace.go:171","msg":"trace[820069558] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"102.2188ms","start":"2026-03-24T15:02:57.421934Z","end":"2026-03-24T15:02:57.524152Z","steps":["trace[820069558] 'process raft request' (duration: 101.916874ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:57.683992Z","caller":"traceutil/trace.go:171","msg":"trace[1642573200] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"148.29782ms","start":"2026-03-24T15:02:57.535655Z","end":"2026-03-24T15:02:57.683953Z","steps":["trace[1642573200] 'process raft request' (duration: 121.921313ms)","trace[1642573200] 'compare' (duration: 26.161083ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:02:57.692144Z","caller":"traceutil/trace.go:171","msg":"trace[1346387454] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"149.229429ms","start":"2026-03-24T15:02:57.542883Z","end":"2026-03-24T15:02:57.692112Z","steps":["trace[1346387454] 'process raft request' (duration: 149.179508ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:02:57.692453Z","caller":"traceutil/trace.go:171","msg":"trace[468844289] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"149.701849ms","start":"2026-03-24T15:02:57.542703Z","end":"2026-03-24T15:02:57.692405Z","steps":["trace[468844289] 'process raft request' (duration: 149.24615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:02:57.959082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.176539ms","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-03-24T15:02:57.959166Z","caller":"traceutil/trace.go:171","msg":"trace[1275439150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4769; }","duration":"105.335882ms","start":"2026-03-24T15:02:57.853811Z","end":"2026-03-24T15:02:57.959147Z","steps":["trace[1275439150] 'range keys from in-memory index tree' (duration: 104.942225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:03:05.56908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.427341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"warn","ts":"2026-03-24T15:03:05.569111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.162996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T15:03:05.569177Z","caller":"traceutil/trace.go:171","msg":"trace[1685477557] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4929; }","duration":"162.538784ms","start":"2026-03-24T15:03:05.406618Z","end":"2026-03-24T15:03:05.569156Z","steps":["trace[1685477557] 'range keys from in-memory index tree' (duration: 162.272288ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:03:05.569221Z","caller":"traceutil/trace.go:171","msg":"trace[339599460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4929; }","duration":"155.283859ms","start":"2026-03-24T15:03:05.413916Z","end":"2026-03-24T15:03:05.5692Z","steps":["trace[339599460] 'range keys from in-memory index tree' (duration: 155.067175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:03:05.570291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.1972ms","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-03-24T15:03:05.57043Z","caller":"traceutil/trace.go:171","msg":"trace[36233797] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:4929; }","duration":"106.393203ms","start":"2026-03-24T15:03:05.464009Z","end":"2026-03-24T15:03:05.570402Z","steps":["trace[36233797] 'range keys from in-memory index tree' (duration: 104.981945ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:03:05.878176Z","caller":"traceutil/trace.go:171","msg":"trace[2047503712] linearizableReadLoop","detail":"{readStateIndex:5123; appliedIndex:5121; }","duration":"274.048789ms","start":"2026-03-24T15:03:05.60408Z","end":"2026-03-24T15:03:05.878128Z","steps":["trace[2047503712] 'read index received' (duration: 86.461515ms)","trace[2047503712] 'applied index is now lower than readState.Index' (duration: 187.585794ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:03:05.878279Z","caller":"traceutil/trace.go:171","msg":"trace[1215358950] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"275.386275ms","start":"2026-03-24T15:03:05.602851Z","end":"2026-03-24T15:03:05.878238Z","steps":["trace[1215358950] 'process raft request' (duration: 204.908549ms)","trace[1215358950] 'compare' (duration: 70.17811ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T15:03:05.878594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.394971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.189fcf30491c8986\" ","response":"range_response_count:1 size:1012"} {"level":"warn","ts":"2026-03-24T15:03:05.878608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.493379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-03-24T15:03:05.878669Z","caller":"traceutil/trace.go:171","msg":"trace[1618549091] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.189fcf30491c8986; range_end:; response_count:1; response_revision:4931; }","duration":"159.507673ms","start":"2026-03-24T15:03:05.719141Z","end":"2026-03-24T15:03:05.878649Z","steps":["trace[1618549091] 'agreement among raft nodes before linearized reading' (duration: 159.225148ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:03:05.878712Z","caller":"traceutil/trace.go:171","msg":"trace[194847137] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4931; }","duration":"274.651011ms","start":"2026-03-24T15:03:05.604033Z","end":"2026-03-24T15:03:05.878684Z","steps":["trace[194847137] 'agreement among raft nodes before linearized reading' (duration: 274.298514ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:03:16.890896Z","caller":"traceutil/trace.go:171","msg":"trace[939029480] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"127.688778ms","start":"2026-03-24T15:03:16.763185Z","end":"2026-03-24T15:03:16.890874Z","steps":["trace[939029480] 'process raft request' (duration: 90.480866ms)","trace[939029480] 'compare' (duration: 37.032259ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:03:16.891332Z","caller":"traceutil/trace.go:171","msg":"trace[2060290024] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"128.105377ms","start":"2026-03-24T15:03:16.7632Z","end":"2026-03-24T15:03:16.891305Z","steps":["trace[2060290024] 'process raft request' (duration: 127.622447ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:03:16.891477Z","caller":"traceutil/trace.go:171","msg":"trace[1917206192] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"127.748199ms","start":"2026-03-24T15:03:16.763701Z","end":"2026-03-24T15:03:16.89145Z","steps":["trace[1917206192] 'process raft request' (duration: 127.354881ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:03:16.891577Z","caller":"traceutil/trace.go:171","msg":"trace[421010241] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"127.751319ms","start":"2026-03-24T15:03:16.763714Z","end":"2026-03-24T15:03:16.891465Z","steps":["trace[421010241] 'process raft request' (duration: 127.604276ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:03:16.891482Z","caller":"traceutil/trace.go:171","msg":"trace[1666953088] linearizableReadLoop","detail":"{readStateIndex:5282; appliedIndex:5278; }","duration":"106.170179ms","start":"2026-03-24T15:03:16.785285Z","end":"2026-03-24T15:03:16.891455Z","steps":["trace[1666953088] 'read index received' (duration: 68.431896ms)","trace[1666953088] 'applied index is now lower than readState.Index' (duration: 37.737403ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T15:03:16.891675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.361102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-03-24T15:03:16.891745Z","caller":"traceutil/trace.go:171","msg":"trace[21344910] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5088; }","duration":"106.500955ms","start":"2026-03-24T15:03:16.785229Z","end":"2026-03-24T15:03:16.89173Z","steps":["trace[21344910] 'agreement among raft nodes before linearized reading' (duration: 106.305491ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:04:42.356796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2749} {"level":"info","ts":"2026-03-24T15:04:42.413944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2749,"took":"55.706532ms","hash":4125272119,"current-db-size-bytes":41623552,"current-db-size":"42 MB","current-db-size-in-use-bytes":31002624,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-24T15:04:42.414025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4125272119,"revision":2749,"compact-revision":-1} {"level":"warn","ts":"2026-03-24T15:09:05.718525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.717572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T15:09:05.718634Z","caller":"traceutil/trace.go:171","msg":"trace[1734006871] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:7556; }","duration":"282.870826ms","start":"2026-03-24T15:09:05.435741Z","end":"2026-03-24T15:09:05.718612Z","steps":["trace[1734006871] 'count revisions from in-memory index tree' (duration: 282.628381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:09:05.718766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.763457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-03-24T15:09:05.71892Z","caller":"traceutil/trace.go:171","msg":"trace[1192564092] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7556; }","duration":"192.946611ms","start":"2026-03-24T15:09:05.525946Z","end":"2026-03-24T15:09:05.718892Z","steps":["trace[1192564092] 'range keys from in-memory index tree' (duration: 192.542882ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:05.872835Z","caller":"traceutil/trace.go:171","msg":"trace[791550445] transaction","detail":"{read_only:false; response_revision:7557; number_of_response:1; }","duration":"149.308471ms","start":"2026-03-24T15:09:05.723492Z","end":"2026-03-24T15:09:05.872801Z","steps":["trace[791550445] 'process raft request' (duration: 149.082656ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:05.883995Z","caller":"traceutil/trace.go:171","msg":"trace[275234845] transaction","detail":"{read_only:false; response_revision:7559; number_of_response:1; }","duration":"117.024496ms","start":"2026-03-24T15:09:05.766919Z","end":"2026-03-24T15:09:05.883944Z","steps":["trace[275234845] 'process raft request' (duration: 116.944704ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:05.884019Z","caller":"traceutil/trace.go:171","msg":"trace[274427513] transaction","detail":"{read_only:false; response_revision:7558; number_of_response:1; }","duration":"160.219338ms","start":"2026-03-24T15:09:05.723751Z","end":"2026-03-24T15:09:05.88397Z","steps":["trace[274427513] 'process raft request' (duration: 159.902882ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:17.326593Z","caller":"traceutil/trace.go:171","msg":"trace[69067018] transaction","detail":"{read_only:false; response_revision:7613; number_of_response:1; }","duration":"203.720225ms","start":"2026-03-24T15:09:17.122848Z","end":"2026-03-24T15:09:17.326568Z","steps":["trace[69067018] 'process raft request' (duration: 203.561192ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:18.09295Z","caller":"traceutil/trace.go:171","msg":"trace[2136690004] transaction","detail":"{read_only:false; response_revision:7615; number_of_response:1; }","duration":"165.591194ms","start":"2026-03-24T15:09:17.927334Z","end":"2026-03-24T15:09:18.092925Z","steps":["trace[2136690004] 'process raft request' (duration: 165.437211ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:18.188187Z","caller":"traceutil/trace.go:171","msg":"trace[1849061435] transaction","detail":"{read_only:false; response_revision:7616; number_of_response:1; }","duration":"259.660463ms","start":"2026-03-24T15:09:17.928483Z","end":"2026-03-24T15:09:18.188144Z","steps":["trace[1849061435] 'process raft request' (duration: 247.840067ms)","trace[1849061435] 'compare' (duration: 11.636062ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:09:18.958859Z","caller":"traceutil/trace.go:171","msg":"trace[1372937866] linearizableReadLoop","detail":"{readStateIndex:7899; appliedIndex:7898; }","duration":"123.555266ms","start":"2026-03-24T15:09:18.835277Z","end":"2026-03-24T15:09:18.958832Z","steps":["trace[1372937866] 'read index received' (duration: 123.282251ms)","trace[1372937866] 'applied index is now lower than readState.Index' (duration: 271.605µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:09:18.958975Z","caller":"traceutil/trace.go:171","msg":"trace[1604830133] transaction","detail":"{read_only:false; response_revision:7618; number_of_response:1; }","duration":"150.045915ms","start":"2026-03-24T15:09:18.808895Z","end":"2026-03-24T15:09:18.958941Z","steps":["trace[1604830133] 'process raft request' (duration: 149.76839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:09:18.959123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.826501ms","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-03-24T15:09:18.959173Z","caller":"traceutil/trace.go:171","msg":"trace[525357730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7618; }","duration":"124.003794ms","start":"2026-03-24T15:09:18.835153Z","end":"2026-03-24T15:09:18.959157Z","steps":["trace[525357730] 'agreement among raft nodes before linearized reading' (duration: 123.827431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T15:09:18.959211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.099597ms","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-03-24T15:09:18.959293Z","caller":"traceutil/trace.go:171","msg":"trace[1376984433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7618; }","duration":"123.22511ms","start":"2026-03-24T15:09:18.83605Z","end":"2026-03-24T15:09:18.959275Z","steps":["trace[1376984433] 'agreement among raft nodes before linearized reading' (duration: 122.997035ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:42.363953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5712} {"level":"info","ts":"2026-03-24T15:09:42.451552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5712,"took":"86.020967ms","hash":3766503983,"current-db-size-bytes":45584384,"current-db-size":"46 MB","current-db-size-in-use-bytes":23654400,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-24T15:09:42.451645Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3766503983,"revision":5712,"compact-revision":2749} {"level":"info","ts":"2026-03-24T15:09:46.34777Z","caller":"traceutil/trace.go:171","msg":"trace[1734852898] transaction","detail":"{read_only:false; response_revision:7808; number_of_response:1; }","duration":"116.039845ms","start":"2026-03-24T15:09:46.231705Z","end":"2026-03-24T15:09:46.347745Z","steps":["trace[1734852898] 'process raft request' (duration: 115.887072ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:46.348629Z","caller":"traceutil/trace.go:171","msg":"trace[495858593] transaction","detail":"{read_only:false; response_revision:7809; number_of_response:1; }","duration":"113.411573ms","start":"2026-03-24T15:09:46.235209Z","end":"2026-03-24T15:09:46.348621Z","steps":["trace[495858593] 'process raft request' (duration: 113.26397ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:48.471671Z","caller":"traceutil/trace.go:171","msg":"trace[1718372926] transaction","detail":"{read_only:false; response_revision:7818; number_of_response:1; }","duration":"117.036886ms","start":"2026-03-24T15:09:48.354605Z","end":"2026-03-24T15:09:48.471642Z","steps":["trace[1718372926] 'process raft request' (duration: 116.791171ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:48.637017Z","caller":"traceutil/trace.go:171","msg":"trace[170774750] transaction","detail":"{read_only:false; response_revision:7819; number_of_response:1; }","duration":"279.755183ms","start":"2026-03-24T15:09:48.357214Z","end":"2026-03-24T15:09:48.636969Z","steps":["trace[170774750] 'process raft request' (duration: 279.474888ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:58.868346Z","caller":"traceutil/trace.go:171","msg":"trace[573431277] transaction","detail":"{read_only:false; response_revision:7908; number_of_response:1; }","duration":"177.302368ms","start":"2026-03-24T15:09:58.691011Z","end":"2026-03-24T15:09:58.868314Z","steps":["trace[573431277] 'process raft request' (duration: 101.18871ms)","trace[573431277] 'compare' (duration: 75.934105ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:09:58.874308Z","caller":"traceutil/trace.go:171","msg":"trace[1989509222] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7909; }","duration":"168.924902ms","start":"2026-03-24T15:09:58.70534Z","end":"2026-03-24T15:09:58.874265Z","steps":["trace[1989509222] 'process raft request' (duration: 168.664126ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:59.420901Z","caller":"traceutil/trace.go:171","msg":"trace[873560956] linearizableReadLoop","detail":"{readStateIndex:8209; appliedIndex:8208; }","duration":"188.565693ms","start":"2026-03-24T15:09:59.232306Z","end":"2026-03-24T15:09:59.420871Z","steps":["trace[873560956] 'read index received' (duration: 93.915994ms)","trace[873560956] 'applied index is now lower than readState.Index' (duration: 94.648099ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:09:59.42109Z","caller":"traceutil/trace.go:171","msg":"trace[1888234745] transaction","detail":"{read_only:false; response_revision:7914; number_of_response:1; }","duration":"238.439448ms","start":"2026-03-24T15:09:59.182629Z","end":"2026-03-24T15:09:59.421068Z","steps":["trace[1888234745] 'process raft request' (duration: 143.714578ms)","trace[1888234745] 'compare' (duration: 94.378594ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T15:09:59.421384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.070774ms","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-03-24T15:09:59.42143Z","caller":"traceutil/trace.go:171","msg":"trace[1451663396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7914; }","duration":"189.178586ms","start":"2026-03-24T15:09:59.232236Z","end":"2026-03-24T15:09:59.421415Z","steps":["trace[1451663396] 'agreement among raft nodes before linearized reading' (duration: 189.009203ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:09:59.609555Z","caller":"traceutil/trace.go:171","msg":"trace[1715379667] transaction","detail":"{read_only:false; response_revision:7915; number_of_response:1; }","duration":"183.707857ms","start":"2026-03-24T15:09:59.425809Z","end":"2026-03-24T15:09:59.609517Z","steps":["trace[1715379667] 'process raft request' (duration: 172.411592ms)","trace[1715379667] 'compare' (duration: 11.03717ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T15:11:01.145195Z","caller":"traceutil/trace.go:171","msg":"trace[2013015331] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"120.786071ms","start":"2026-03-24T15:11:01.024387Z","end":"2026-03-24T15:11:01.145173Z","steps":["trace[2013015331] 'process raft request' (duration: 120.614977ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T15:13:37.449138Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"79e65b4197a2a1a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-24T15:13:37.454325Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-24T15:13:37.454423Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-24T15:14:42.371548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7783} {"level":"info","ts":"2026-03-24T15:14:42.434122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7783,"took":"60.09528ms","hash":3206147960,"current-db-size-bytes":45584384,"current-db-size":"46 MB","current-db-size-in-use-bytes":25825280,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-24T15:14:42.434208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3206147960,"revision":7783,"compact-revision":5712} {"level":"info","ts":"2026-03-24T15:19:42.377291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9982} {"level":"info","ts":"2026-03-24T15:19:42.459971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9982,"took":"80.771771ms","hash":3318943335,"current-db-size-bytes":45584384,"current-db-size":"46 MB","current-db-size-in-use-bytes":16814080,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-24T15:19:42.46004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3318943335,"revision":9982,"compact-revision":7783}