{"level":"warn","ts":"2026-04-06T20:59:48.861166Z","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-06T20:59:48.86128Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33: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.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-06T20:59:48.861371Z","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-06T20:59:48.861392Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-06T20:59:48.861417Z","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-06T20:59:48.862309Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-04-06T20:59:48.862402Z","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":8,"max-cpu-available":8,"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.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-06T20:59:48.866989Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.275354ms"} {"level":"info","ts":"2026-04-06T20:59:48.875695Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-04-06T20:59:48.875812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T20:59:48.875967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-04-06T20:59:48.875993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T20:59:48.876008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-04-06T20:59:48.87607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-04-06T20:59:48.887723Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T20:59:48.889899Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T20:59:48.895726Z","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-06T20:59:48.898278Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T20:59:48.898682Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-06T20:59:48.898769Z","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-06T20:59:48.898965Z","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-06T20:59:48.899119Z","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-06T20:59:48.900875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-04-06T20:59:48.901361Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-06T20:59:48.902923Z","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-06T20:59:48.903169Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T20:59:48.903195Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-06T20:59:48.903242Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-06T20:59:48.903197Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T20:59:49.580747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T20:59:49.580796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T20:59:49.580824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-04-06T20:59:49.580837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-04-06T20:59:49.580844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-06T20:59:49.580854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-04-06T20:59:49.580863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-06T20:59:49.581858Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T20:59:49.582656Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T20:59:49.582704Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T20:59:49.582798Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T20:59:49.583049Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T20:59:49.58312Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T20:59:49.583196Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T20:59:49.583258Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T20:59:49.583274Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T20:59:49.585263Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-04-06T20:59:49.586071Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-06T20:59:51.195023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.144271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-06T20:59:51.195266Z","caller":"traceutil/trace.go:171","msg":"trace[291923745] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"339.878218ms","start":"2026-04-06T20:59:50.855375Z","end":"2026-04-06T20:59:51.195253Z","steps":["trace[291923745] 'process raft request' (duration: 339.846827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.195369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.855348Z","time spent":"339.992009ms","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:51.195514Z","caller":"traceutil/trace.go:171","msg":"trace[311607633] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"342.390552ms","start":"2026-04-06T20:59:50.853111Z","end":"2026-04-06T20:59:51.195502Z","steps":["trace[311607633] 'process raft request' (duration: 342.084909ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:51.195479Z","caller":"traceutil/trace.go:171","msg":"trace[623781334] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"416.889117ms","start":"2026-04-06T20:59:50.778542Z","end":"2026-04-06T20:59:51.195431Z","steps":["trace[623781334] 'process raft request' (duration: 416.603095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.195576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.853089Z","time spent":"342.453193ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:51.195259Z","caller":"traceutil/trace.go:171","msg":"trace[1828151515] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"424.822769ms","start":"2026-04-06T20:59:50.770404Z","end":"2026-04-06T20:59:51.195227Z","steps":["trace[1828151515] 'process raft request' (duration: 232.83699ms)","trace[1828151515] 'compare' (duration: 191.021779ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:51.195816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.778525Z","time spent":"417.03742ms","remote":"127.0.0.1:44380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:51.195795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.77039Z","time spent":"425.270124ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T20:59:51.271185Z","caller":"traceutil/trace.go:171","msg":"trace[1619266313] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"414.872998ms","start":"2026-04-06T20:59:50.856265Z","end":"2026-04-06T20:59:51.271138Z","steps":["trace[1619266313] 'process raft request' (duration: 414.593085ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:51.271209Z","caller":"traceutil/trace.go:171","msg":"trace[1265919101] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"414.702896ms","start":"2026-04-06T20:59:50.856476Z","end":"2026-04-06T20:59:51.271179Z","steps":["trace[1265919101] 'process raft request' (duration: 414.522974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.271274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.856228Z","time spent":"415.01037ms","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:51.271333Z","caller":"traceutil/trace.go:171","msg":"trace[1972494898] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"414.468854ms","start":"2026-04-06T20:59:50.856822Z","end":"2026-04-06T20:59:51.271291Z","steps":["trace[1972494898] 'process raft request' (duration: 414.244432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.27142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.856815Z","time spent":"414.563275ms","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:51.271356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.856466Z","time spent":"414.815417ms","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:51.271755Z","caller":"traceutil/trace.go:171","msg":"trace[1705361838] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"414.567825ms","start":"2026-04-06T20:59:50.85712Z","end":"2026-04-06T20:59:51.271688Z","steps":["trace[1705361838] 'process raft request' (duration: 413.976439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.272016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.857108Z","time spent":"414.740077ms","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T20:59:51.272555Z","caller":"traceutil/trace.go:171","msg":"trace[529374095] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:5; }","duration":"415.686587ms","start":"2026-04-06T20:59:50.856854Z","end":"2026-04-06T20:59:51.272541Z","steps":["trace[529374095] 'read index received' (duration: 146.480864ms)","trace[529374095] 'applied index is now lower than readState.Index' (duration: 269.204763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T20:59:51.272803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.06346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:51.272808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.599757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:51.272851Z","caller":"traceutil/trace.go:171","msg":"trace[616553613] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"452.147701ms","start":"2026-04-06T20:59:50.820691Z","end":"2026-04-06T20:59:51.272838Z","steps":["trace[616553613] 'agreement among raft nodes before linearized reading' (duration: 451.928659ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:51.272871Z","caller":"traceutil/trace.go:171","msg":"trace[654712862] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:0; response_revision:11; }","duration":"421.694958ms","start":"2026-04-06T20:59:50.851159Z","end":"2026-04-06T20:59:51.272854Z","steps":["trace[654712862] 'agreement among raft nodes before linearized reading' (duration: 421.449686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.272891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.82068Z","time spent":"452.197551ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-06T20:59:51.272974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.851145Z","time spent":"421.813919ms","remote":"127.0.0.1:44140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.33\" "} {"level":"warn","ts":"2026-04-06T20:59:51.273169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.027361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:51.273189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.171263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:51.273153Z","caller":"traceutil/trace.go:171","msg":"trace[1753009425] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"410.925287ms","start":"2026-04-06T20:59:50.862162Z","end":"2026-04-06T20:59:51.273088Z","steps":["trace[1753009425] 'process raft request' (duration: 409.587415ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:51.273218Z","caller":"traceutil/trace.go:171","msg":"trace[336684059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"497.145903ms","start":"2026-04-06T20:59:50.776058Z","end":"2026-04-06T20:59:51.273204Z","steps":["trace[336684059] 'agreement among raft nodes before linearized reading' (duration: 497.063871ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T20:59:51.273242Z","caller":"traceutil/trace.go:171","msg":"trace[1107706505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"496.235513ms","start":"2026-04-06T20:59:50.776986Z","end":"2026-04-06T20:59:51.273222Z","steps":["trace[1107706505] 'agreement among raft nodes before linearized reading' (duration: 496.154543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.273254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.77605Z","time spent":"497.197454ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-06T20:59:51.273274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.776976Z","time spent":"496.292054ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-06T20:59:51.273291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.562597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:51.273337Z","caller":"traceutil/trace.go:171","msg":"trace[2051021947] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"419.632097ms","start":"2026-04-06T20:59:50.85369Z","end":"2026-04-06T20:59:51.273323Z","steps":["trace[2051021947] 'agreement among raft nodes before linearized reading' (duration: 419.563667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.273349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.598198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:51.273379Z","caller":"traceutil/trace.go:171","msg":"trace[467924493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"498.675438ms","start":"2026-04-06T20:59:50.774696Z","end":"2026-04-06T20:59:51.273371Z","steps":["trace[467924493] 'agreement among raft nodes before linearized reading' (duration: 498.635117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.27338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.853678Z","time spent":"419.691788ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-06T20:59:51.273403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.774666Z","time spent":"498.732339ms","remote":"127.0.0.1:44102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-06T20:59:51.273335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.862153Z","time spent":"411.09686ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T20:59:51.273623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.148003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-04-06T20:59:51.273673Z","caller":"traceutil/trace.go:171","msg":"trace[1646179384] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"499.218094ms","start":"2026-04-06T20:59:50.774441Z","end":"2026-04-06T20:59:51.273659Z","steps":["trace[1646179384] 'agreement among raft nodes before linearized reading' (duration: 499.095442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.273687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.969729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T20:59:51.273714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.774436Z","time spent":"499.270735ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-06T20:59:51.273738Z","caller":"traceutil/trace.go:171","msg":"trace[903448554] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"417.074151ms","start":"2026-04-06T20:59:50.856647Z","end":"2026-04-06T20:59:51.273721Z","steps":["trace[903448554] 'agreement among raft nodes before linearized reading' (duration: 416.966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.273777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.856633Z","time spent":"417.135852ms","remote":"127.0.0.1:44190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-06T20:59:51.276734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.608513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T20:59:51.276778Z","caller":"traceutil/trace.go:171","msg":"trace[1507506596] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:13; }","duration":"371.664644ms","start":"2026-04-06T20:59:50.905101Z","end":"2026-04-06T20:59:51.276765Z","steps":["trace[1507506596] 'agreement among raft nodes before linearized reading' (duration: 371.592993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T20:59:51.276809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T20:59:50.905092Z","time spent":"371.708734ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-06T21:00:17.857507Z","caller":"traceutil/trace.go:171","msg":"trace[1799351399] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"114.547177ms","start":"2026-04-06T21:00:17.742926Z","end":"2026-04-06T21:00:17.857473Z","steps":["trace[1799351399] 'process raft request' (duration: 114.359265ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:17.864632Z","caller":"traceutil/trace.go:171","msg":"trace[1267838271] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"115.469635ms","start":"2026-04-06T21:00:17.74915Z","end":"2026-04-06T21:00:17.86462Z","steps":["trace[1267838271] 'process raft request' (duration: 115.327724ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:17.86465Z","caller":"traceutil/trace.go:171","msg":"trace[1513601014] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"117.827533ms","start":"2026-04-06T21:00:17.746778Z","end":"2026-04-06T21:00:17.864606Z","steps":["trace[1513601014] 'process raft request' (duration: 117.542781ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:17.86474Z","caller":"traceutil/trace.go:171","msg":"trace[1954340628] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"110.879367ms","start":"2026-04-06T21:00:17.753854Z","end":"2026-04-06T21:00:17.864734Z","steps":["trace[1954340628] 'process raft request' (duration: 110.662915ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:17.864712Z","caller":"traceutil/trace.go:171","msg":"trace[2129120551] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"110.772746ms","start":"2026-04-06T21:00:17.753934Z","end":"2026-04-06T21:00:17.864707Z","steps":["trace[2129120551] 'process raft request' (duration: 110.618885ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:17.864983Z","caller":"traceutil/trace.go:171","msg":"trace[1418390701] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"106.970025ms","start":"2026-04-06T21:00:17.758005Z","end":"2026-04-06T21:00:17.864975Z","steps":["trace[1418390701] 'process raft request' (duration: 106.667102ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:17.865055Z","caller":"traceutil/trace.go:171","msg":"trace[1551633565] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"110.941537ms","start":"2026-04-06T21:00:17.754107Z","end":"2026-04-06T21:00:17.865048Z","steps":["trace[1551633565] 'process raft request' (duration: 110.483954ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:17.86514Z","caller":"traceutil/trace.go:171","msg":"trace[1699846078] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"110.394333ms","start":"2026-04-06T21:00:17.754716Z","end":"2026-04-06T21:00:17.86511Z","steps":["trace[1699846078] 'process raft request' (duration: 109.915359ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:24.533211Z","caller":"traceutil/trace.go:171","msg":"trace[2114805425] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"437.608035ms","start":"2026-04-06T21:00:24.095572Z","end":"2026-04-06T21:00:24.53318Z","steps":["trace[2114805425] 'read index received' (duration: 436.734388ms)","trace[2114805425] 'applied index is now lower than readState.Index' (duration: 872.557µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:00:24.533342Z","caller":"traceutil/trace.go:171","msg":"trace[222063274] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"471.500302ms","start":"2026-04-06T21:00:24.061762Z","end":"2026-04-06T21:00:24.533263Z","steps":["trace[222063274] 'process raft request' (duration: 470.756625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.533811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:24.061726Z","time spent":"471.809594ms","remote":"127.0.0.1:44200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:00:24.534299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.690583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-48t8n\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-06T21:00:24.53442Z","caller":"traceutil/trace.go:171","msg":"trace[918440957] range","detail":"{range_begin:/registry/pods/kube-system/cilium-48t8n; range_end:; response_count:1; response_revision:573; }","duration":"438.881956ms","start":"2026-04-06T21:00:24.095508Z","end":"2026-04-06T21:00:24.53439Z","steps":["trace[918440957] 'agreement among raft nodes before linearized reading' (duration: 437.810497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:24.534475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:24.095485Z","time spent":"438.980806ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-48t8n\" "} {"level":"warn","ts":"2026-04-06T21:00:25.24931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.611103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:00:25.249607Z","caller":"traceutil/trace.go:171","msg":"trace[1591046359] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"644.0253ms","start":"2026-04-06T21:00:24.605534Z","end":"2026-04-06T21:00:25.249559Z","steps":["trace[1591046359] 'process raft request' (duration: 393.018924ms)","trace[1591046359] 'compare' (duration: 250.239811ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:00:25.249723Z","caller":"traceutil/trace.go:171","msg":"trace[801709520] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"197.636428ms","start":"2026-04-06T21:00:25.052042Z","end":"2026-04-06T21:00:25.249678Z","steps":["trace[801709520] 'read index received' (duration: 193.256604ms)","trace[801709520] 'applied index is now lower than readState.Index' (duration: 4.378334ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:00:25.249795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:24.605501Z","time spent":"644.201531ms","remote":"127.0.0.1:44652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T21:00:25.250205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.161673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:00:25.250246Z","caller":"traceutil/trace.go:171","msg":"trace[24940618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"198.252654ms","start":"2026-04-06T21:00:25.05198Z","end":"2026-04-06T21:00:25.250233Z","steps":["trace[24940618] 'agreement among raft nodes before linearized reading' (duration: 198.160853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:25.250398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.274446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-06T21:00:25.250451Z","caller":"traceutil/trace.go:171","msg":"trace[39502512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"169.389258ms","start":"2026-04-06T21:00:25.081049Z","end":"2026-04-06T21:00:25.250438Z","steps":["trace[39502512] 'agreement among raft nodes before linearized reading' (duration: 169.214266ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:00:25.25041Z","caller":"traceutil/trace.go:171","msg":"trace[33647981] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"392.199437ms","start":"2026-04-06T21:00:24.858198Z","end":"2026-04-06T21:00:25.250397Z","steps":["trace[33647981] 'process raft request' (duration: 391.327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:00:25.250776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:00:24.858143Z","time spent":"392.586181ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:00:25.250686Z","caller":"traceutil/trace.go:171","msg":"trace[319103848] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"265.239638ms","start":"2026-04-06T21:00:24.985432Z","end":"2026-04-06T21:00:25.250672Z","steps":["trace[319103848] 'process raft request' (duration: 264.19216ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:05.671637Z","caller":"traceutil/trace.go:171","msg":"trace[1171992449] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"370.049718ms","start":"2026-04-06T21:02:05.301554Z","end":"2026-04-06T21:02:05.671604Z","steps":["trace[1171992449] 'process raft request' (duration: 369.732223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:05.671817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:05.301524Z","time spent":"370.20262ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:02:05.812055Z","caller":"traceutil/trace.go:171","msg":"trace[1668887835] linearizableReadLoop","detail":"{readStateIndex:1870; appliedIndex:1869; }","duration":"117.951426ms","start":"2026-04-06T21:02:05.694074Z","end":"2026-04-06T21:02:05.812025Z","steps":["trace[1668887835] 'read index received' (duration: 117.820065ms)","trace[1668887835] 'applied index is now lower than readState.Index' (duration: 130.091µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:02:05.812231Z","caller":"traceutil/trace.go:171","msg":"trace[300452023] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"121.895747ms","start":"2026-04-06T21:02:05.690229Z","end":"2026-04-06T21:02:05.812125Z","steps":["trace[300452023] 'process raft request' (duration: 121.625874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:05.812498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.383001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-06T21:02:05.812571Z","caller":"traceutil/trace.go:171","msg":"trace[2114322889] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1820; }","duration":"118.511282ms","start":"2026-04-06T21:02:05.694042Z","end":"2026-04-06T21:02:05.812553Z","steps":["trace[2114322889] 'agreement among raft nodes before linearized reading' (duration: 118.099258ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:06.028788Z","caller":"traceutil/trace.go:171","msg":"trace[155419034] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"211.217854ms","start":"2026-04-06T21:02:05.817523Z","end":"2026-04-06T21:02:06.028741Z","steps":["trace[155419034] 'process raft request' (duration: 196.715098ms)","trace[155419034] 'compare' (duration: 13.969689ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:02:06.066531Z","caller":"traceutil/trace.go:171","msg":"trace[1768392629] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"247.101594ms","start":"2026-04-06T21:02:05.819382Z","end":"2026-04-06T21:02:06.066484Z","steps":["trace[1768392629] 'process raft request' (duration: 246.752009ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:06.066696Z","caller":"traceutil/trace.go:171","msg":"trace[1498709544] linearizableReadLoop","detail":"{readStateIndex:1876; appliedIndex:1870; }","duration":"237.785413ms","start":"2026-04-06T21:02:05.828887Z","end":"2026-04-06T21:02:06.066672Z","steps":["trace[1498709544] 'read index received' (duration: 185.365802ms)","trace[1498709544] 'applied index is now lower than readState.Index' (duration: 52.418041ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:02:06.066672Z","caller":"traceutil/trace.go:171","msg":"trace[1212563998] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"247.139083ms","start":"2026-04-06T21:02:05.819403Z","end":"2026-04-06T21:02:06.066542Z","steps":["trace[1212563998] 'process raft request' (duration: 246.912791ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:06.066828Z","caller":"traceutil/trace.go:171","msg":"trace[330416571] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"247.396627ms","start":"2026-04-06T21:02:05.819416Z","end":"2026-04-06T21:02:06.066813Z","steps":["trace[330416571] 'process raft request' (duration: 247.001312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:06.067169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.263209ms","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-06T21:02:06.067307Z","caller":"traceutil/trace.go:171","msg":"trace[2124711479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1826; }","duration":"238.424071ms","start":"2026-04-06T21:02:05.82885Z","end":"2026-04-06T21:02:06.067274Z","steps":["trace[2124711479] 'agreement among raft nodes before linearized reading' (duration: 238.138348ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:06.067357Z","caller":"traceutil/trace.go:171","msg":"trace[23451297] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"247.64989ms","start":"2026-04-06T21:02:05.819692Z","end":"2026-04-06T21:02:06.067342Z","steps":["trace[23451297] 'process raft request' (duration: 246.79927ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:06.06746Z","caller":"traceutil/trace.go:171","msg":"trace[296722111] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"247.231114ms","start":"2026-04-06T21:02:05.820217Z","end":"2026-04-06T21:02:06.067449Z","steps":["trace[296722111] 'process raft request' (duration: 246.344214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:06.067303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.741725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-06T21:02:06.067649Z","caller":"traceutil/trace.go:171","msg":"trace[1429057248] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1826; }","duration":"190.143991ms","start":"2026-04-06T21:02:05.877491Z","end":"2026-04-06T21:02:06.067635Z","steps":["trace[1429057248] 'agreement among raft nodes before linearized reading' (duration: 189.620993ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:11.823252Z","caller":"traceutil/trace.go:171","msg":"trace[1857756041] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"101.436746ms","start":"2026-04-06T21:02:11.721765Z","end":"2026-04-06T21:02:11.823202Z","steps":["trace[1857756041] 'process raft request' (duration: 101.221933ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:43.597227Z","caller":"traceutil/trace.go:171","msg":"trace[135169641] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"111.211591ms","start":"2026-04-06T21:02:43.485996Z","end":"2026-04-06T21:02:43.597207Z","steps":["trace[135169641] 'read index received' (duration: 111.172791ms)","trace[135169641] 'applied index is now lower than readState.Index' (duration: 37.8µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:02:43.597319Z","caller":"traceutil/trace.go:171","msg":"trace[116917609] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"169.156369ms","start":"2026-04-06T21:02:43.42814Z","end":"2026-04-06T21:02:43.597297Z","steps":["trace[116917609] 'process raft request' (duration: 168.907487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:43.597435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.426393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-06T21:02:43.597464Z","caller":"traceutil/trace.go:171","msg":"trace[1398475689] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2126; }","duration":"111.537985ms","start":"2026-04-06T21:02:43.485914Z","end":"2026-04-06T21:02:43.597452Z","steps":["trace[1398475689] 'agreement among raft nodes before linearized reading' (duration: 111.317282ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:43.861089Z","caller":"traceutil/trace.go:171","msg":"trace[2012644163] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"168.702634ms","start":"2026-04-06T21:02:43.692357Z","end":"2026-04-06T21:02:43.86106Z","steps":["trace[2012644163] 'read index received' (duration: 168.534352ms)","trace[2012644163] 'applied index is now lower than readState.Index' (duration: 167.452µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:02:43.861236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.865786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T21:02:43.861273Z","caller":"traceutil/trace.go:171","msg":"trace[1398376539] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2127; }","duration":"168.974998ms","start":"2026-04-06T21:02:43.692287Z","end":"2026-04-06T21:02:43.861262Z","steps":["trace[1398376539] 'agreement among raft nodes before linearized reading' (duration: 168.871467ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:43.861457Z","caller":"traceutil/trace.go:171","msg":"trace[2042045571] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"256.255597ms","start":"2026-04-06T21:02:43.605184Z","end":"2026-04-06T21:02:43.861439Z","steps":["trace[2042045571] 'process raft request' (duration: 255.748681ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:44.060988Z","caller":"traceutil/trace.go:171","msg":"trace[289262554] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"110.710495ms","start":"2026-04-06T21:02:43.950244Z","end":"2026-04-06T21:02:44.060955Z","steps":["trace[289262554] 'process raft request' (duration: 110.666154ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:44.061031Z","caller":"traceutil/trace.go:171","msg":"trace[874467279] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"192.706375ms","start":"2026-04-06T21:02:43.868277Z","end":"2026-04-06T21:02:44.060984Z","steps":["trace[874467279] 'process raft request' (duration: 163.645257ms)","trace[874467279] 'compare' (duration: 28.856516ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:02:44.061244Z","caller":"traceutil/trace.go:171","msg":"trace[1294580316] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"161.655513ms","start":"2026-04-06T21:02:43.899569Z","end":"2026-04-06T21:02:44.061224Z","steps":["trace[1294580316] 'read index received' (duration: 132.461735ms)","trace[1294580316] 'applied index is now lower than readState.Index' (duration: 29.191628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:02:44.061478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.880057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T21:02:44.061529Z","caller":"traceutil/trace.go:171","msg":"trace[1164325417] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2129; }","duration":"161.979298ms","start":"2026-04-06T21:02:43.899537Z","end":"2026-04-06T21:02:44.061516Z","steps":["trace[1164325417] 'agreement among raft nodes before linearized reading' (duration: 161.795756ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:03:37.179435Z","caller":"traceutil/trace.go:171","msg":"trace[1510076736] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"127.712927ms","start":"2026-04-06T21:03:37.051694Z","end":"2026-04-06T21:03:37.179407Z","steps":["trace[1510076736] 'read index received' (duration: 127.545326ms)","trace[1510076736] 'applied index is now lower than readState.Index' (duration: 166.351µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:03:37.179584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.868089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:03:37.179548Z","caller":"traceutil/trace.go:171","msg":"trace[1692516208] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"164.279982ms","start":"2026-04-06T21:03:37.015236Z","end":"2026-04-06T21:03:37.179516Z","steps":["trace[1692516208] 'process raft request' (duration: 163.979919ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:03:37.179626Z","caller":"traceutil/trace.go:171","msg":"trace[1110125229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2594; }","duration":"127.9432ms","start":"2026-04-06T21:03:37.051668Z","end":"2026-04-06T21:03:37.179611Z","steps":["trace[1110125229] 'agreement among raft nodes before linearized reading' (duration: 127.854509ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:04:08.799194Z","caller":"traceutil/trace.go:171","msg":"trace[601210826] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"151.666062ms","start":"2026-04-06T21:04:08.647491Z","end":"2026-04-06T21:04:08.799157Z","steps":["trace[601210826] 'process raft request' (duration: 129.111852ms)","trace[601210826] 'compare' (duration: 22.394159ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:04:08.992068Z","caller":"traceutil/trace.go:171","msg":"trace[1824318844] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"189.285043ms","start":"2026-04-06T21:04:08.802749Z","end":"2026-04-06T21:04:08.992034Z","steps":["trace[1824318844] 'process raft request' (duration: 156.619949ms)","trace[1824318844] 'compare' (duration: 32.498352ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:26.407715Z","caller":"traceutil/trace.go:171","msg":"trace[1272017873] linearizableReadLoop","detail":"{readStateIndex:4499; appliedIndex:4496; }","duration":"100.899562ms","start":"2026-04-06T21:06:26.306796Z","end":"2026-04-06T21:06:26.407696Z","steps":["trace[1272017873] 'read index received' (duration: 30.100332ms)","trace[1272017873] 'applied index is now lower than readState.Index' (duration: 70.79843ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:26.407736Z","caller":"traceutil/trace.go:171","msg":"trace[1263330634] transaction","detail":"{read_only:false; response_revision:4314; number_of_response:1; }","duration":"105.699095ms","start":"2026-04-06T21:06:26.302009Z","end":"2026-04-06T21:06:26.407708Z","steps":["trace[1263330634] 'process raft request' (duration: 105.44509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:26.408007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.187657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:06:26.408058Z","caller":"traceutil/trace.go:171","msg":"trace[673591829] transaction","detail":"{read_only:false; response_revision:4315; number_of_response:1; }","duration":"101.393001ms","start":"2026-04-06T21:06:26.306612Z","end":"2026-04-06T21:06:26.408005Z","steps":["trace[673591829] 'process raft request' (duration: 100.987274ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:26.408145Z","caller":"traceutil/trace.go:171","msg":"trace[2077193187] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:0; response_revision:4315; }","duration":"101.352161ms","start":"2026-04-06T21:06:26.306774Z","end":"2026-04-06T21:06:26.408126Z","steps":["trace[2077193187] 'agreement among raft nodes before linearized reading' (duration: 101.138217ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:27.864333Z","caller":"traceutil/trace.go:171","msg":"trace[1922685476] transaction","detail":"{read_only:false; response_revision:4382; number_of_response:1; }","duration":"105.942372ms","start":"2026-04-06T21:06:27.758372Z","end":"2026-04-06T21:06:27.864315Z","steps":["trace[1922685476] 'process raft request' (duration: 105.83833ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:27.87092Z","caller":"traceutil/trace.go:171","msg":"trace[814881961] transaction","detail":"{read_only:false; response_revision:4383; number_of_response:1; }","duration":"110.167254ms","start":"2026-04-06T21:06:27.760731Z","end":"2026-04-06T21:06:27.870898Z","steps":["trace[814881961] 'process raft request' (duration: 109.655324ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:29.290278Z","caller":"traceutil/trace.go:171","msg":"trace[586497197] linearizableReadLoop","detail":"{readStateIndex:4581; appliedIndex:4580; }","duration":"267.176835ms","start":"2026-04-06T21:06:29.023072Z","end":"2026-04-06T21:06:29.290249Z","steps":["trace[586497197] 'read index received' (duration: 267.027471ms)","trace[586497197] 'applied index is now lower than readState.Index' (duration: 148.044µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:29.290404Z","caller":"traceutil/trace.go:171","msg":"trace[121449780] transaction","detail":"{read_only:false; response_revision:4396; number_of_response:1; }","duration":"289.173346ms","start":"2026-04-06T21:06:29.001197Z","end":"2026-04-06T21:06:29.29037Z","steps":["trace[121449780] 'process raft request' (duration: 288.88797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:29.290575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.107267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:06:29.290677Z","caller":"traceutil/trace.go:171","msg":"trace[1797322980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4396; }","duration":"240.24437ms","start":"2026-04-06T21:06:29.050419Z","end":"2026-04-06T21:06:29.290664Z","steps":["trace[1797322980] 'agreement among raft nodes before linearized reading' (duration: 240.089317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:29.290693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.506441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ee0a1b6-145a-491f-bdf9-a4598ec965a2\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-06T21:06:29.290746Z","caller":"traceutil/trace.go:171","msg":"trace[1711661691] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3ee0a1b6-145a-491f-bdf9-a4598ec965a2; range_end:; response_count:1; response_revision:4396; }","duration":"267.693605ms","start":"2026-04-06T21:06:29.023037Z","end":"2026-04-06T21:06:29.290731Z","steps":["trace[1711661691] 'agreement among raft nodes before linearized reading' (duration: 267.40301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:29.71894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.490749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:06:29.719289Z","caller":"traceutil/trace.go:171","msg":"trace[1247116934] linearizableReadLoop","detail":"{readStateIndex:4584; appliedIndex:4581; }","duration":"395.45496ms","start":"2026-04-06T21:06:29.323757Z","end":"2026-04-06T21:06:29.719212Z","steps":["trace[1247116934] 'read index received' (duration: 89.607265ms)","trace[1247116934] 'applied index is now lower than readState.Index' (duration: 305.846835ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:29.719391Z","caller":"traceutil/trace.go:171","msg":"trace[35953738] transaction","detail":"{read_only:false; response_revision:4397; number_of_response:1; }","duration":"422.228183ms","start":"2026-04-06T21:06:29.297151Z","end":"2026-04-06T21:06:29.719379Z","steps":["trace[35953738] 'process raft request' (duration: 116.202685ms)","trace[35953738] 'compare' (duration: 305.270024ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:06:29.719453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:06:29.297129Z","time spent":"422.288515ms","remote":"127.0.0.1:44208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:06:29.719672Z","caller":"traceutil/trace.go:171","msg":"trace[26044870] transaction","detail":"{read_only:false; response_revision:4399; number_of_response:1; }","duration":"402.574517ms","start":"2026-04-06T21:06:29.317065Z","end":"2026-04-06T21:06:29.71964Z","steps":["trace[26044870] 'process raft request' (duration: 402.099618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:29.719839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:06:29.317052Z","time spent":"402.688319ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:06:29.719851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.898623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-06T21:06:29.719892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.129972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18a3e09003ba18b3\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-04-06T21:06:29.719902Z","caller":"traceutil/trace.go:171","msg":"trace[1942831021] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4399; }","duration":"356.012755ms","start":"2026-04-06T21:06:29.363875Z","end":"2026-04-06T21:06:29.719888Z","steps":["trace[1942831021] 'agreement among raft nodes before linearized reading' (duration: 355.847642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:29.719929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:06:29.363856Z","time spent":"356.068106ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-04-06T21:06:29.719933Z","caller":"traceutil/trace.go:171","msg":"trace[1887659947] range","detail":"{range_begin:/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18a3e09003ba18b3; range_end:; response_count:1; response_revision:4399; }","duration":"396.184215ms","start":"2026-04-06T21:06:29.323734Z","end":"2026-04-06T21:06:29.719919Z","steps":["trace[1887659947] 'agreement among raft nodes before linearized reading' (duration: 396.059023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:29.719968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:06:29.323724Z","time spent":"396.233766ms","remote":"127.0.0.1:44182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":1,"response size":1202,"request content":"key:\"/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18a3e09003ba18b3\" "} {"level":"warn","ts":"2026-04-06T21:06:29.719944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.296025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-06T21:06:29.71997Z","caller":"traceutil/trace.go:171","msg":"trace[663422458] transaction","detail":"{read_only:false; response_revision:4398; number_of_response:1; }","duration":"421.269385ms","start":"2026-04-06T21:06:29.298678Z","end":"2026-04-06T21:06:29.719947Z","steps":["trace[663422458] 'process raft request' (duration: 420.385038ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:29.720024Z","caller":"traceutil/trace.go:171","msg":"trace[1743355208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4399; }","duration":"222.414638ms","start":"2026-04-06T21:06:29.497589Z","end":"2026-04-06T21:06:29.720003Z","steps":["trace[1743355208] 'agreement among raft nodes before linearized reading' (duration: 222.182312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:29.720087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:06:29.298659Z","time spent":"421.363677ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3878,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:06:30.009749Z","caller":"traceutil/trace.go:171","msg":"trace[1197638168] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"285.131988ms","start":"2026-04-06T21:06:29.72459Z","end":"2026-04-06T21:06:30.009722Z","steps":["trace[1197638168] 'process raft request' (duration: 285.053177ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:30.009783Z","caller":"traceutil/trace.go:171","msg":"trace[1866786306] transaction","detail":"{read_only:false; response_revision:4401; number_of_response:1; }","duration":"286.106507ms","start":"2026-04-06T21:06:29.723645Z","end":"2026-04-06T21:06:30.009752Z","steps":["trace[1866786306] 'process raft request' (duration: 192.506646ms)","trace[1866786306] 'compare' (duration: 93.362876ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:30.010109Z","caller":"traceutil/trace.go:171","msg":"trace[1516720531] linearizableReadLoop","detail":"{readStateIndex:4586; appliedIndex:4584; }","duration":"286.129749ms","start":"2026-04-06T21:06:29.723948Z","end":"2026-04-06T21:06:30.010077Z","steps":["trace[1516720531] 'read index received' (duration: 16.13241ms)","trace[1516720531] 'applied index is now lower than readState.Index' (duration: 269.995498ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:30.01026Z","caller":"traceutil/trace.go:171","msg":"trace[1547079174] transaction","detail":"{read_only:false; response_revision:4403; number_of_response:1; }","duration":"285.307772ms","start":"2026-04-06T21:06:29.724918Z","end":"2026-04-06T21:06:30.010225Z","steps":["trace[1547079174] 'process raft request' (duration: 284.768401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:30.010379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.235831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-nggdl\" ","response":"range_response_count:1 size:5140"} {"level":"warn","ts":"2026-04-06T21:06:30.010393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.426034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-06T21:06:30.010415Z","caller":"traceutil/trace.go:171","msg":"trace[861597914] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-nggdl; range_end:; response_count:1; response_revision:4403; }","duration":"285.310972ms","start":"2026-04-06T21:06:29.725094Z","end":"2026-04-06T21:06:30.010405Z","steps":["trace[861597914] 'agreement among raft nodes before linearized reading' (duration: 285.170129ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:30.01045Z","caller":"traceutil/trace.go:171","msg":"trace[547469490] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4403; }","duration":"286.514755ms","start":"2026-04-06T21:06:29.723917Z","end":"2026-04-06T21:06:30.010431Z","steps":["trace[547469490] 'agreement among raft nodes before linearized reading' (duration: 286.309371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:30.010556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.752703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-06T21:06:30.010577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.471826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T21:06:30.010625Z","caller":"traceutil/trace.go:171","msg":"trace[831259697] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:4403; }","duration":"191.586647ms","start":"2026-04-06T21:06:29.819031Z","end":"2026-04-06T21:06:30.010617Z","steps":["trace[831259697] 'agreement among raft nodes before linearized reading' (duration: 191.507686ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:30.010626Z","caller":"traceutil/trace.go:171","msg":"trace[687474708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4403; }","duration":"141.838825ms","start":"2026-04-06T21:06:29.868742Z","end":"2026-04-06T21:06:30.010581Z","steps":["trace[687474708] 'agreement among raft nodes before linearized reading' (duration: 141.688642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:30.010849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.43048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0.18a3e08ffa142673\" ","response":"range_response_count:1 size:1203"} {"level":"warn","ts":"2026-04-06T21:06:30.010836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.903402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-06T21:06:30.010878Z","caller":"traceutil/trace.go:171","msg":"trace[1588423731] range","detail":"{range_begin:/registry/events/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0.18a3e08ffa142673; range_end:; response_count:1; response_revision:4403; }","duration":"267.48612ms","start":"2026-04-06T21:06:29.743382Z","end":"2026-04-06T21:06:30.010869Z","steps":["trace[1588423731] 'agreement among raft nodes before linearized reading' (duration: 267.352768ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:30.010911Z","caller":"traceutil/trace.go:171","msg":"trace[90514625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4403; }","duration":"135.011194ms","start":"2026-04-06T21:06:29.875884Z","end":"2026-04-06T21:06:30.010896Z","steps":["trace[90514625] 'agreement among raft nodes before linearized reading' (duration: 134.741529ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:34.222108Z","caller":"traceutil/trace.go:171","msg":"trace[1426182158] linearizableReadLoop","detail":"{readStateIndex:4740; appliedIndex:4739; }","duration":"181.014448ms","start":"2026-04-06T21:06:34.041076Z","end":"2026-04-06T21:06:34.22209Z","steps":["trace[1426182158] 'read index received' (duration: 180.847895ms)","trace[1426182158] 'applied index is now lower than readState.Index' (duration: 165.693µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:34.222373Z","caller":"traceutil/trace.go:171","msg":"trace[2080496674] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"191.656198ms","start":"2026-04-06T21:06:34.030705Z","end":"2026-04-06T21:06:34.222361Z","steps":["trace[2080496674] 'process raft request' (duration: 191.268791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:34.222674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.582888ms","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-06T21:06:34.222714Z","caller":"traceutil/trace.go:171","msg":"trace[1720254158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4549; }","duration":"181.663249ms","start":"2026-04-06T21:06:34.041042Z","end":"2026-04-06T21:06:34.222705Z","steps":["trace[1720254158] 'agreement among raft nodes before linearized reading' (duration: 181.514586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:34.222918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.317921ms","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-06T21:06:34.222953Z","caller":"traceutil/trace.go:171","msg":"trace[1037923542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4549; }","duration":"167.394001ms","start":"2026-04-06T21:06:34.055551Z","end":"2026-04-06T21:06:34.222945Z","steps":["trace[1037923542] 'agreement among raft nodes before linearized reading' (duration: 167.3035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:34.223643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.63291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:06:34.223687Z","caller":"traceutil/trace.go:171","msg":"trace[568244070] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4549; }","duration":"129.681442ms","start":"2026-04-06T21:06:34.093996Z","end":"2026-04-06T21:06:34.223677Z","steps":["trace[568244070] 'agreement among raft nodes before linearized reading' (duration: 129.620441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:34.223852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.188932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-06T21:06:34.223887Z","caller":"traceutil/trace.go:171","msg":"trace[22291699] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4549; }","duration":"163.250272ms","start":"2026-04-06T21:06:34.060629Z","end":"2026-04-06T21:06:34.223879Z","steps":["trace[22291699] 'agreement among raft nodes before linearized reading' (duration: 163.169961ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:37.180027Z","caller":"traceutil/trace.go:171","msg":"trace[1544253951] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"149.924675ms","start":"2026-04-06T21:06:37.03008Z","end":"2026-04-06T21:06:37.180005Z","steps":["trace[1544253951] 'process raft request' (duration: 149.668979ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:37.18685Z","caller":"traceutil/trace.go:171","msg":"trace[1539595999] linearizableReadLoop","detail":"{readStateIndex:4800; appliedIndex:4798; }","duration":"116.42413ms","start":"2026-04-06T21:06:37.070401Z","end":"2026-04-06T21:06:37.186825Z","steps":["trace[1539595999] 'read index received' (duration: 109.382809ms)","trace[1539595999] 'applied index is now lower than readState.Index' (duration: 7.040281ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:37.186997Z","caller":"traceutil/trace.go:171","msg":"trace[1651094630] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"147.026271ms","start":"2026-04-06T21:06:37.039942Z","end":"2026-04-06T21:06:37.186969Z","steps":["trace[1651094630] 'process raft request' (duration: 146.723445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:37.187244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.821818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-06T21:06:37.1873Z","caller":"traceutil/trace.go:171","msg":"trace[1871285641] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4608; }","duration":"116.89595ms","start":"2026-04-06T21:06:37.070388Z","end":"2026-04-06T21:06:37.187284Z","steps":["trace[1871285641] 'agreement among raft nodes before linearized reading' (duration: 116.529193ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:09.956295Z","caller":"traceutil/trace.go:171","msg":"trace[1449388769] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"115.346041ms","start":"2026-04-06T21:07:09.840918Z","end":"2026-04-06T21:07:09.956264Z","steps":["trace[1449388769] 'process raft request' (duration: 96.886289ms)","trace[1449388769] 'compare' (duration: 18.003255ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:10.831108Z","caller":"traceutil/trace.go:171","msg":"trace[1955094957] linearizableReadLoop","detail":"{readStateIndex:5259; appliedIndex:5258; }","duration":"149.429021ms","start":"2026-04-06T21:07:10.681654Z","end":"2026-04-06T21:07:10.831083Z","steps":["trace[1955094957] 'read index received' (duration: 149.122127ms)","trace[1955094957] 'applied index is now lower than readState.Index' (duration: 305.584µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:10.831169Z","caller":"traceutil/trace.go:171","msg":"trace[1218240748] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"242.327159ms","start":"2026-04-06T21:07:10.588799Z","end":"2026-04-06T21:07:10.831126Z","steps":["trace[1218240748] 'process raft request' (duration: 242.091755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:10.831413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.740067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-06T21:07:10.831489Z","caller":"traceutil/trace.go:171","msg":"trace[1167898336] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5051; }","duration":"149.884019ms","start":"2026-04-06T21:07:10.681584Z","end":"2026-04-06T21:07:10.831468Z","steps":["trace[1167898336] 'agreement among raft nodes before linearized reading' (duration: 149.628065ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:11.246778Z","caller":"traceutil/trace.go:171","msg":"trace[1612408921] linearizableReadLoop","detail":"{readStateIndex:5261; appliedIndex:5260; }","duration":"195.884106ms","start":"2026-04-06T21:07:11.050852Z","end":"2026-04-06T21:07:11.246736Z","steps":["trace[1612408921] 'read index received' (duration: 178.002365ms)","trace[1612408921] 'applied index is now lower than readState.Index' (duration: 17.874901ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:11.246822Z","caller":"traceutil/trace.go:171","msg":"trace[517613136] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"355.587199ms","start":"2026-04-06T21:07:10.891201Z","end":"2026-04-06T21:07:11.246788Z","steps":["trace[517613136] 'process raft request' (duration: 337.748999ms)","trace[517613136] 'compare' (duration: 16.518658ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:07:11.246996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.12574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:07:11.247076Z","caller":"traceutil/trace.go:171","msg":"trace[1890674191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5053; }","duration":"196.273562ms","start":"2026-04-06T21:07:11.050783Z","end":"2026-04-06T21:07:11.247057Z","steps":["trace[1890674191] 'agreement among raft nodes before linearized reading' (duration: 196.09523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:11.247161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.478242ms","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":"warn","ts":"2026-04-06T21:07:11.247126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:07:10.891184Z","time spent":"355.722131ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:07:11.247203Z","caller":"traceutil/trace.go:171","msg":"trace[1742720956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5053; }","duration":"163.572963ms","start":"2026-04-06T21:07:11.083618Z","end":"2026-04-06T21:07:11.247191Z","steps":["trace[1742720956] 'agreement among raft nodes before linearized reading' (duration: 163.355279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:11.247093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.206775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T21:07:11.247603Z","caller":"traceutil/trace.go:171","msg":"trace[1662162817] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5053; }","duration":"132.760695ms","start":"2026-04-06T21:07:11.114816Z","end":"2026-04-06T21:07:11.247576Z","steps":["trace[1662162817] 'agreement among raft nodes before linearized reading' (duration: 132.181956ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:34.793475Z","caller":"traceutil/trace.go:171","msg":"trace[1395833387] linearizableReadLoop","detail":"{readStateIndex:5466; appliedIndex:5464; }","duration":"102.979811ms","start":"2026-04-06T21:07:34.690466Z","end":"2026-04-06T21:07:34.793446Z","steps":["trace[1395833387] 'read index received' (duration: 29.598609ms)","trace[1395833387] 'applied index is now lower than readState.Index' (duration: 73.380132ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:34.793576Z","caller":"traceutil/trace.go:171","msg":"trace[328755574] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"119.071007ms","start":"2026-04-06T21:07:34.674471Z","end":"2026-04-06T21:07:34.793542Z","steps":["trace[328755574] 'process raft request' (duration: 118.769612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:34.793779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.276956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-06T21:07:34.794037Z","caller":"traceutil/trace.go:171","msg":"trace[1154371412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5247; }","duration":"103.590982ms","start":"2026-04-06T21:07:34.690427Z","end":"2026-04-06T21:07:34.794018Z","steps":["trace[1154371412] 'agreement among raft nodes before linearized reading' (duration: 103.137304ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:09:49.609067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3176} {"level":"info","ts":"2026-04-06T21:09:49.707744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3176,"took":"97.299747ms","hash":3414963733,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":31457280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-06T21:09:49.707816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3414963733,"revision":3176,"compact-revision":-1}