{"level":"warn","ts":"2026-08-21T02:29:04.078414Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-21T02:29:04.078487Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.131: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.131:2380","--initial-cluster=instance=https://199.19.213.131:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.131:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.131: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-08-21T02:29:04.07854Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-21T02:29:04.078554Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.131:2380"]} {"level":"info","ts":"2026-08-21T02:29:04.07857Z","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-08-21T02:29:04.079353Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.131:2379"]} {"level":"info","ts":"2026-08-21T02:29:04.079437Z","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.19.213.131:2380"],"listen-peer-urls":["https://199.19.213.131:2380"],"advertise-client-urls":["https://199.19.213.131:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.131:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.131: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-08-21T02:29:04.083767Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.099804ms"} {"level":"info","ts":"2026-08-21T02:29:04.106445Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a55ede269ddc9f19","cluster-id":"c1b2f50c13a3baa5"} {"level":"info","ts":"2026-08-21T02:29:04.106572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 switched to configuration voters=()"} {"level":"info","ts":"2026-08-21T02:29:04.106595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 became follower at term 0"} {"level":"info","ts":"2026-08-21T02:29:04.106605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a55ede269ddc9f19 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-21T02:29:04.106615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 became follower at term 1"} {"level":"info","ts":"2026-08-21T02:29:04.106659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 switched to configuration voters=(11916205921507516185)"} {"level":"warn","ts":"2026-08-21T02:29:04.121454Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-21T02:29:04.123414Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-21T02:29:04.125586Z","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-08-21T02:29:04.130537Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a55ede269ddc9f19","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-21T02:29:04.131228Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a55ede269ddc9f19","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-21T02:29:04.131493Z","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-08-21T02:29:04.13159Z","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-08-21T02:29:04.131734Z","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-08-21T02:29:04.131696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 switched to configuration voters=(11916205921507516185)"} {"level":"info","ts":"2026-08-21T02:29:04.133207Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c1b2f50c13a3baa5","local-member-id":"a55ede269ddc9f19","added-peer-id":"a55ede269ddc9f19","added-peer-peer-urls":["https://199.19.213.131:2380"]} {"level":"info","ts":"2026-08-21T02:29:04.135084Z","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-08-21T02:29:04.135213Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.131:2380"} {"level":"info","ts":"2026-08-21T02:29:04.135274Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.131:2380"} {"level":"info","ts":"2026-08-21T02:29:04.135469Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a55ede269ddc9f19","initial-advertise-peer-urls":["https://199.19.213.131:2380"],"listen-peer-urls":["https://199.19.213.131:2380"],"advertise-client-urls":["https://199.19.213.131:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.131:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-21T02:29:04.135514Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-21T02:29:04.806876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 is starting a new election at term 1"} {"level":"info","ts":"2026-08-21T02:29:04.806956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-21T02:29:04.807011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 received MsgPreVoteResp from a55ede269ddc9f19 at term 1"} {"level":"info","ts":"2026-08-21T02:29:04.807031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 became candidate at term 2"} {"level":"info","ts":"2026-08-21T02:29:04.807042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 received MsgVoteResp from a55ede269ddc9f19 at term 2"} {"level":"info","ts":"2026-08-21T02:29:04.807059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a55ede269ddc9f19 became leader at term 2"} {"level":"info","ts":"2026-08-21T02:29:04.807073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a55ede269ddc9f19 elected leader a55ede269ddc9f19 at term 2"} {"level":"info","ts":"2026-08-21T02:29:04.808124Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a55ede269ddc9f19","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.131:2379]}","request-path":"/0/members/a55ede269ddc9f19/attributes","cluster-id":"c1b2f50c13a3baa5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-21T02:29:04.808115Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:29:04.808379Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:29:04.808563Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:29:04.809113Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-21T02:29:04.809209Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-21T02:29:04.809348Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c1b2f50c13a3baa5","local-member-id":"a55ede269ddc9f19","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:29:04.809523Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:29:04.809604Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:29:04.811985Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-21T02:29:04.81302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.131:2379"} {"level":"info","ts":"2026-08-21T02:29:23.766705Z","caller":"traceutil/trace.go:171","msg":"trace[1200407903] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"246.835152ms","start":"2026-08-21T02:29:23.519824Z","end":"2026-08-21T02:29:23.766659Z","steps":["trace[1200407903] 'process raft request' (duration: 162.180992ms)","trace[1200407903] 'compare' (duration: 84.449378ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:29:23.912296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.529636ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-21T02:29:41.889326Z","caller":"traceutil/trace.go:171","msg":"trace[607758586] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"132.910102ms","start":"2026-08-21T02:29:41.756391Z","end":"2026-08-21T02:29:41.889301Z","steps":["trace[607758586] 'process raft request' (duration: 102.987509ms)","trace[607758586] 'compare' (duration: 29.80922ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:29:41.894292Z","caller":"traceutil/trace.go:171","msg":"trace[897531785] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"135.071509ms","start":"2026-08-21T02:29:41.759197Z","end":"2026-08-21T02:29:41.894268Z","steps":["trace[897531785] 'process raft request' (duration: 134.995921ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.894695Z","caller":"traceutil/trace.go:171","msg":"trace[1346166706] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"135.143307ms","start":"2026-08-21T02:29:41.759508Z","end":"2026-08-21T02:29:41.894651Z","steps":["trace[1346166706] 'process raft request' (duration: 134.839055ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.894741Z","caller":"traceutil/trace.go:171","msg":"trace[128330488] transaction","detail":"{read_only:false; number_of_response:1; response_revision:494; }","duration":"135.440798ms","start":"2026-08-21T02:29:41.759257Z","end":"2026-08-21T02:29:41.894698Z","steps":["trace[128330488] 'process raft request' (duration: 134.980179ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.894837Z","caller":"traceutil/trace.go:171","msg":"trace[609234104] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"126.002426ms","start":"2026-08-21T02:29:41.768827Z","end":"2026-08-21T02:29:41.89483Z","steps":["trace[609234104] 'process raft request' (duration: 125.56267ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.894915Z","caller":"traceutil/trace.go:171","msg":"trace[1467339827] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"125.916538ms","start":"2026-08-21T02:29:41.768993Z","end":"2026-08-21T02:29:41.894909Z","steps":["trace[1467339827] 'process raft request' (duration: 125.472781ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.894954Z","caller":"traceutil/trace.go:171","msg":"trace[1061859516] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"135.485913ms","start":"2026-08-21T02:29:41.759457Z","end":"2026-08-21T02:29:41.894942Z","steps":["trace[1061859516] 'process raft request' (duration: 134.852166ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.894985Z","caller":"traceutil/trace.go:171","msg":"trace[288639834] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"125.988825ms","start":"2026-08-21T02:29:41.768991Z","end":"2026-08-21T02:29:41.89498Z","steps":["trace[288639834] 'process raft request' (duration: 125.516516ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.895042Z","caller":"traceutil/trace.go:171","msg":"trace[1624590692] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"116.749284ms","start":"2026-08-21T02:29:41.778287Z","end":"2026-08-21T02:29:41.895036Z","steps":["trace[1624590692] 'process raft request' (duration: 116.252031ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.895102Z","caller":"traceutil/trace.go:171","msg":"trace[95197292] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"114.552012ms","start":"2026-08-21T02:29:41.780545Z","end":"2026-08-21T02:29:41.895097Z","steps":["trace[95197292] 'process raft request' (duration: 114.024067ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.895265Z","caller":"traceutil/trace.go:171","msg":"trace[1042719283] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"136.12383ms","start":"2026-08-21T02:29:41.759135Z","end":"2026-08-21T02:29:41.895259Z","steps":["trace[1042719283] 'process raft request' (duration: 134.98977ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.894789Z","caller":"traceutil/trace.go:171","msg":"trace[907851708] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"134.073803ms","start":"2026-08-21T02:29:41.760704Z","end":"2026-08-21T02:29:41.894778Z","steps":["trace[907851708] 'process raft request' (duration: 133.66024ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:41.894574Z","caller":"traceutil/trace.go:171","msg":"trace[2052244523] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"135.510794ms","start":"2026-08-21T02:29:41.759033Z","end":"2026-08-21T02:29:41.894544Z","steps":["trace[2052244523] 'process raft request' (duration: 134.938925ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:47.67682Z","caller":"traceutil/trace.go:171","msg":"trace[976289814] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"105.828712ms","start":"2026-08-21T02:29:47.570967Z","end":"2026-08-21T02:29:47.676795Z","steps":["trace[976289814] 'process raft request' (duration: 104.361107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:29:47.951429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.86407ms","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-08-21T02:29:47.951515Z","caller":"traceutil/trace.go:171","msg":"trace[540507604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"228.037359ms","start":"2026-08-21T02:29:47.723458Z","end":"2026-08-21T02:29:47.951495Z","steps":["trace[540507604] 'range keys from in-memory index tree' (duration: 227.712834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:29:47.952182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.389591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:29:47.952738Z","caller":"traceutil/trace.go:171","msg":"trace[815882216] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"209.700426ms","start":"2026-08-21T02:29:47.743011Z","end":"2026-08-21T02:29:47.952711Z","steps":["trace[815882216] 'process raft request' (duration: 100.381167ms)","trace[815882216] 'compare' (duration: 108.056706ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:29:48.453494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.562515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-slbl6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-21T02:29:48.453586Z","caller":"traceutil/trace.go:171","msg":"trace[2022900926] range","detail":"{range_begin:/registry/pods/kube-system/cilium-slbl6; range_end:; response_count:1; response_revision:593; }","duration":"193.70959ms","start":"2026-08-21T02:29:48.259855Z","end":"2026-08-21T02:29:48.453565Z","steps":["trace[2022900926] 'range keys from in-memory index tree' (duration: 193.362134ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:48.7213Z","caller":"traceutil/trace.go:171","msg":"trace[1187773913] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"316.034666ms","start":"2026-08-21T02:29:48.405237Z","end":"2026-08-21T02:29:48.721272Z","steps":["trace[1187773913] 'process raft request' (duration: 315.50531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:29:48.721637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:29:48.405207Z","time spent":"316.171891ms","remote":"127.0.0.1:56588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:29:48.722724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.326007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.131\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-21T02:29:48.722811Z","caller":"traceutil/trace.go:171","msg":"trace[1505641040] range","detail":"{range_begin:/registry/masterleases/199.19.213.131; range_end:; response_count:1; response_revision:594; }","duration":"305.458621ms","start":"2026-08-21T02:29:48.417332Z","end":"2026-08-21T02:29:48.722791Z","steps":["trace[1505641040] 'agreement among raft nodes before linearized reading' (duration: 305.291022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:29:48.722857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:29:48.417312Z","time spent":"305.535698ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.131\" "} {"level":"info","ts":"2026-08-21T02:29:48.723341Z","caller":"traceutil/trace.go:171","msg":"trace[620193313] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:621; }","duration":"305.044338ms","start":"2026-08-21T02:29:48.417385Z","end":"2026-08-21T02:29:48.722429Z","steps":["trace[620193313] 'read index received' (duration: 305.035766ms)","trace[620193313] 'applied index is now lower than readState.Index' (duration: 7.272µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:29:48.729185Z","caller":"traceutil/trace.go:171","msg":"trace[1177787121] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"271.837947ms","start":"2026-08-21T02:29:48.457331Z","end":"2026-08-21T02:29:48.729169Z","steps":["trace[1177787121] 'process raft request' (duration: 271.544265ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:48.729279Z","caller":"traceutil/trace.go:171","msg":"trace[2120539725] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"262.785442ms","start":"2026-08-21T02:29:48.466476Z","end":"2026-08-21T02:29:48.729261Z","steps":["trace[2120539725] 'process raft request' (duration: 262.504722ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:32.751458Z","caller":"traceutil/trace.go:171","msg":"trace[530986161] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"144.877614ms","start":"2026-08-21T02:30:32.60656Z","end":"2026-08-21T02:30:32.751438Z","steps":["trace[530986161] 'read index received' (duration: 117.32154ms)","trace[530986161] 'applied index is now lower than readState.Index' (duration: 27.555354ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:30:32.751602Z","caller":"traceutil/trace.go:171","msg":"trace[538390281] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"235.370138ms","start":"2026-08-21T02:30:32.516205Z","end":"2026-08-21T02:30:32.751575Z","steps":["trace[538390281] 'process raft request' (duration: 207.613263ms)","trace[538390281] 'compare' (duration: 27.532067ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:30:32.751682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.085933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:30:32.751764Z","caller":"traceutil/trace.go:171","msg":"trace[520796329] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1159; }","duration":"145.243367ms","start":"2026-08-21T02:30:32.606511Z","end":"2026-08-21T02:30:32.751754Z","steps":["trace[520796329] 'agreement among raft nodes before linearized reading' (duration: 145.070904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:31:23.488868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.665125ms","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-08-21T02:31:23.488969Z","caller":"traceutil/trace.go:171","msg":"trace[1158019388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1849; }","duration":"292.809705ms","start":"2026-08-21T02:31:23.196138Z","end":"2026-08-21T02:31:23.488948Z","steps":["trace[1158019388] 'range keys from in-memory index tree' (duration: 292.502705ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:23.489719Z","caller":"traceutil/trace.go:171","msg":"trace[509998935] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"233.155548ms","start":"2026-08-21T02:31:23.256549Z","end":"2026-08-21T02:31:23.489705Z","steps":["trace[509998935] 'read index received' (duration: 168.530579ms)","trace[509998935] 'applied index is now lower than readState.Index' (duration: 64.624169ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:31:23.489833Z","caller":"traceutil/trace.go:171","msg":"trace[674253580] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"267.792809ms","start":"2026-08-21T02:31:23.222003Z","end":"2026-08-21T02:31:23.489796Z","steps":["trace[674253580] 'process raft request' (duration: 203.12512ms)","trace[674253580] 'compare' (duration: 63.553781ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:31:23.49001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.462007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b913a61f-e3b3-4b82-b328-b324ffec1dd0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-21T02:31:23.490054Z","caller":"traceutil/trace.go:171","msg":"trace[1050855449] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b913a61f-e3b3-4b82-b328-b324ffec1dd0; range_end:; response_count:1; response_revision:1850; }","duration":"233.547358ms","start":"2026-08-21T02:31:23.256494Z","end":"2026-08-21T02:31:23.490041Z","steps":["trace[1050855449] 'agreement among raft nodes before linearized reading' (duration: 233.437338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:31:23.490418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.780451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-21T02:31:23.490459Z","caller":"traceutil/trace.go:171","msg":"trace[1879377018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1850; }","duration":"225.84604ms","start":"2026-08-21T02:31:23.264603Z","end":"2026-08-21T02:31:23.490449Z","steps":["trace[1879377018] 'agreement among raft nodes before linearized reading' (duration: 225.736581ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:23.63393Z","caller":"traceutil/trace.go:171","msg":"trace[1628818117] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"137.707553ms","start":"2026-08-21T02:31:23.496198Z","end":"2026-08-21T02:31:23.633906Z","steps":["trace[1628818117] 'process raft request' (duration: 109.295422ms)","trace[1628818117] 'compare' (duration: 28.251522ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:31:23.634455Z","caller":"traceutil/trace.go:171","msg":"trace[922735127] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"137.682783ms","start":"2026-08-21T02:31:23.496727Z","end":"2026-08-21T02:31:23.63441Z","steps":["trace[922735127] 'process raft request' (duration: 137.143304ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:23.974491Z","caller":"traceutil/trace.go:171","msg":"trace[1576344984] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"237.971409ms","start":"2026-08-21T02:31:23.736496Z","end":"2026-08-21T02:31:23.974467Z","steps":["trace[1576344984] 'process raft request' (duration: 237.80946ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:36.947289Z","caller":"traceutil/trace.go:171","msg":"trace[1055739241] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"217.77644ms","start":"2026-08-21T02:31:36.729487Z","end":"2026-08-21T02:31:36.947264Z","steps":["trace[1055739241] 'process raft request' (duration: 217.629497ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:40.357296Z","caller":"traceutil/trace.go:171","msg":"trace[54191493] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"128.746464ms","start":"2026-08-21T02:31:40.228518Z","end":"2026-08-21T02:31:40.357265Z","steps":["trace[54191493] 'process raft request' (duration: 126.976754ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:58.576267Z","caller":"traceutil/trace.go:171","msg":"trace[467070235] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"140.476827ms","start":"2026-08-21T02:31:58.435767Z","end":"2026-08-21T02:31:58.576243Z","steps":["trace[467070235] 'process raft request' (duration: 122.646196ms)","trace[467070235] 'compare' (duration: 17.579412ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:31:58.92812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.709527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:31:58.928314Z","caller":"traceutil/trace.go:171","msg":"trace[1122081704] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2180; }","duration":"341.256458ms","start":"2026-08-21T02:31:58.58704Z","end":"2026-08-21T02:31:58.928297Z","steps":["trace[1122081704] 'read index received' (duration: 114.239851ms)","trace[1122081704] 'applied index is now lower than readState.Index' (duration: 227.015337ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:31:58.928455Z","caller":"traceutil/trace.go:171","msg":"trace[445564193] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"345.610814ms","start":"2026-08-21T02:31:58.58283Z","end":"2026-08-21T02:31:58.928441Z","steps":["trace[445564193] 'process raft request' (duration: 118.496234ms)","trace[445564193] 'compare' (duration: 226.605263ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:31:58.928569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:31:58.582811Z","time spent":"345.717048ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:31:58.928761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.705851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-21T02:31:58.928837Z","caller":"traceutil/trace.go:171","msg":"trace[851167768] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2094; }","duration":"341.815145ms","start":"2026-08-21T02:31:58.587001Z","end":"2026-08-21T02:31:58.928816Z","steps":["trace[851167768] 'agreement among raft nodes before linearized reading' (duration: 341.597528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:31:58.92895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:31:58.586987Z","time spent":"341.95348ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-08-21T02:31:59.621905Z","caller":"traceutil/trace.go:171","msg":"trace[394346755] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"341.289979ms","start":"2026-08-21T02:31:59.28059Z","end":"2026-08-21T02:31:59.62188Z","steps":["trace[394346755] 'process raft request' (duration: 341.124013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:31:59.62212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:31:59.28057Z","time spent":"341.400752ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:12.181847Z","caller":"traceutil/trace.go:171","msg":"trace[139400672] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2253; }","duration":"134.932325ms","start":"2026-08-21T02:32:12.046891Z","end":"2026-08-21T02:32:12.181824Z","steps":["trace[139400672] 'read index received' (duration: 134.717826ms)","trace[139400672] 'applied index is now lower than readState.Index' (duration: 213.559µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:12.181981Z","caller":"traceutil/trace.go:171","msg":"trace[291106909] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"160.679194ms","start":"2026-08-21T02:32:12.021257Z","end":"2026-08-21T02:32:12.181936Z","steps":["trace[291106909] 'process raft request' (duration: 160.420884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:12.182128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.217627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-21T02:32:12.182192Z","caller":"traceutil/trace.go:171","msg":"trace[2109707678] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2164; }","duration":"135.316961ms","start":"2026-08-21T02:32:12.046857Z","end":"2026-08-21T02:32:12.182174Z","steps":["trace[2109707678] 'agreement among raft nodes before linearized reading' (duration: 135.096942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:12.182248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.997947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-21T02:32:12.182335Z","caller":"traceutil/trace.go:171","msg":"trace[2037621850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2164; }","duration":"134.241067ms","start":"2026-08-21T02:32:12.048079Z","end":"2026-08-21T02:32:12.18232Z","steps":["trace[2037621850] 'agreement among raft nodes before linearized reading' (duration: 133.899183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:21.096482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999987883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T02:32:21.096585Z","caller":"traceutil/trace.go:171","msg":"trace[1926866149] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000166472s","start":"2026-08-21T02:32:19.0964Z","end":"2026-08-21T02:32:21.096566Z","steps":["trace[1926866149] 'agreement among raft nodes before linearized reading' (duration: 2.000020455s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:21.096634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:19.096379Z","time spent":"2.000243665s","remote":"127.0.0.1:56496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 02:32:21 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T02:32:22.929671Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.674809312s","expected-duration":"1s"} {"level":"info","ts":"2026-08-21T02:32:22.936863Z","caller":"traceutil/trace.go:171","msg":"trace[1881459142] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2285; }","duration":"4.712391665s","start":"2026-08-21T02:32:18.224436Z","end":"2026-08-21T02:32:22.936828Z","steps":["trace[1881459142] 'read index received' (duration: 30.430067ms)","trace[1881459142] 'applied index is now lower than readState.Index' (duration: 4.681959578s)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:22.937011Z","caller":"traceutil/trace.go:171","msg":"trace[1691924753] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"4.725142212s","start":"2026-08-21T02:32:18.211848Z","end":"2026-08-21T02:32:22.93699Z","steps":["trace[1691924753] 'process raft request' (duration: 4.71809738s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.937834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.21183Z","time spent":"4.725899116s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:22.937279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.712841575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-21T02:32:22.939252Z","caller":"traceutil/trace.go:171","msg":"trace[77292075] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2196; }","duration":"4.714832346s","start":"2026-08-21T02:32:18.224373Z","end":"2026-08-21T02:32:22.939206Z","steps":["trace[77292075] 'agreement among raft nodes before linearized reading' (duration: 4.712681518s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.939377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.224328Z","time spent":"4.715009064s","remote":"127.0.0.1:55564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-21T02:32:22.949564Z","caller":"traceutil/trace.go:171","msg":"trace[1768467272] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"4.395114251s","start":"2026-08-21T02:32:18.554374Z","end":"2026-08-21T02:32:22.949488Z","steps":["trace[1768467272] 'process raft request' (duration: 4.394964454s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:22.949692Z","caller":"traceutil/trace.go:171","msg":"trace[758405497] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"4.285683999s","start":"2026-08-21T02:32:18.663996Z","end":"2026-08-21T02:32:22.94968Z","steps":["trace[758405497] 'process raft request' (duration: 4.285454659s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.94976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.663971Z","time spent":"4.285747321s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:22.949903Z","caller":"traceutil/trace.go:171","msg":"trace[1198950191] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"3.953152684s","start":"2026-08-21T02:32:18.99674Z","end":"2026-08-21T02:32:22.949893Z","steps":["trace[1198950191] 'process raft request' (duration: 3.952893592s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:22.949945Z","caller":"traceutil/trace.go:171","msg":"trace[409870927] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"3.20182188s","start":"2026-08-21T02:32:19.748108Z","end":"2026-08-21T02:32:22.94993Z","steps":["trace[409870927] 'process raft request' (duration: 3.201697635s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.949962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.996715Z","time spent":"3.953211417s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:22.950103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.554354Z","time spent":"4.395714869s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:22.950123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:19.748086Z","time spent":"3.201908092s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:22.950807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495486876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18cdb13f0ab006c1\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-08-21T02:32:22.950887Z","caller":"traceutil/trace.go:171","msg":"trace[1237558171] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18cdb13f0ab006c1; range_end:; response_count:1; response_revision:2201; }","duration":"1.495602281s","start":"2026-08-21T02:32:21.455266Z","end":"2026-08-21T02:32:22.950868Z","steps":["trace[1237558171] 'agreement among raft nodes before linearized reading' (duration: 1.495401323s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.950928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:21.455249Z","time spent":"1.495670525s","remote":"127.0.0.1:56568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18cdb13f0ab006c1\" "} {"level":"warn","ts":"2026-08-21T02:32:22.950972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.691467339s","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-08-21T02:32:22.95106Z","caller":"traceutil/trace.go:171","msg":"trace[1923451210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2201; }","duration":"2.691598675s","start":"2026-08-21T02:32:20.259443Z","end":"2026-08-21T02:32:22.951041Z","steps":["trace[1923451210] 'agreement among raft nodes before linearized reading' (duration: 2.691351914s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:22.951152Z","caller":"traceutil/trace.go:171","msg":"trace[423618505] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"2.571743725s","start":"2026-08-21T02:32:20.37939Z","end":"2026-08-21T02:32:22.951134Z","steps":["trace[423618505] 'process raft request' (duration: 2.570504088s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.951217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:20.259423Z","time spent":"2.691780254s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-21T02:32:22.95128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:20.379363Z","time spent":"2.571829628s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:22.951458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854530003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:22.951498Z","caller":"traceutil/trace.go:171","msg":"trace[837279010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2201; }","duration":"1.854600816s","start":"2026-08-21T02:32:21.096886Z","end":"2026-08-21T02:32:22.951487Z","steps":["trace[837279010] 'agreement among raft nodes before linearized reading' (duration: 1.854538823s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.951525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:21.096873Z","time spent":"1.854646639s","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T02:32:22.951507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.729941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:22.951593Z","caller":"traceutil/trace.go:171","msg":"trace[1193313822] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2201; }","duration":"698.858447ms","start":"2026-08-21T02:32:22.252724Z","end":"2026-08-21T02:32:22.951583Z","steps":["trace[1193313822] 'agreement among raft nodes before linearized reading' (duration: 698.754142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.951628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.252707Z","time spent":"698.909249ms","remote":"127.0.0.1:56724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:22.951692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.249071302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:32:22.951732Z","caller":"traceutil/trace.go:171","msg":"trace[570851162] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2201; }","duration":"2.249182426s","start":"2026-08-21T02:32:20.702538Z","end":"2026-08-21T02:32:22.951721Z","steps":["trace[570851162] 'agreement among raft nodes before linearized reading' (duration: 2.249108214s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.951769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:20.70251Z","time spent":"2.24924937s","remote":"127.0.0.1:55524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:22.951795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.345339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:22.951857Z","caller":"traceutil/trace.go:171","msg":"trace[578700795] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2201; }","duration":"832.444962ms","start":"2026-08-21T02:32:22.119375Z","end":"2026-08-21T02:32:22.95182Z","steps":["trace[578700795] 'agreement among raft nodes before linearized reading' (duration: 832.38928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.951899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.119353Z","time spent":"832.536236ms","remote":"127.0.0.1:44310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:22.951458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.525499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-08-21T02:32:22.951949Z","caller":"traceutil/trace.go:171","msg":"trace[982337587] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2201; }","duration":"647.045983ms","start":"2026-08-21T02:32:22.304893Z","end":"2026-08-21T02:32:22.951939Z","steps":["trace[982337587] 'agreement among raft nodes before linearized reading' (duration: 646.481928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.95199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.304878Z","time spent":"647.103577ms","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12410,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-21T02:32:22.952088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437416756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:32:22.952129Z","caller":"traceutil/trace.go:171","msg":"trace[38387133] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2201; }","duration":"1.43749447s","start":"2026-08-21T02:32:21.514623Z","end":"2026-08-21T02:32:22.952117Z","steps":["trace[38387133] 'agreement among raft nodes before linearized reading' (duration: 1.437411046s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.952159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:21.514592Z","time spent":"1.437559413s","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:22.951864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.807038913s","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-08-21T02:32:22.952219Z","caller":"traceutil/trace.go:171","msg":"trace[2088311731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2201; }","duration":"3.807619539s","start":"2026-08-21T02:32:19.144589Z","end":"2026-08-21T02:32:22.952209Z","steps":["trace[2088311731] 'agreement among raft nodes before linearized reading' (duration: 3.806572041s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.952253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:19.144569Z","time spent":"3.807674261s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-21T02:32:22.950683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.524914956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.131\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-21T02:32:22.952449Z","caller":"traceutil/trace.go:171","msg":"trace[617393374] range","detail":"{range_begin:/registry/masterleases/199.19.213.131; range_end:; response_count:1; response_revision:2201; }","duration":"4.526682696s","start":"2026-08-21T02:32:18.425752Z","end":"2026-08-21T02:32:22.952434Z","steps":["trace[617393374] 'agreement among raft nodes before linearized reading' (duration: 4.5247971s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.952496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:18.425697Z","time spent":"4.52678524s","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.131\" "} {"level":"warn","ts":"2026-08-21T02:32:22.952819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.914434664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-21T02:32:22.952969Z","caller":"traceutil/trace.go:171","msg":"trace[2059311622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2201; }","duration":"3.914646994s","start":"2026-08-21T02:32:19.038298Z","end":"2026-08-21T02:32:22.952945Z","steps":["trace[2059311622] 'agreement among raft nodes before linearized reading' (duration: 3.91411824s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.953243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:19.038287Z","time spent":"3.914940726s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-21T02:32:22.954101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.915783835s","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-08-21T02:32:22.954356Z","caller":"traceutil/trace.go:171","msg":"trace[1876711256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2201; }","duration":"3.916062998s","start":"2026-08-21T02:32:19.038243Z","end":"2026-08-21T02:32:22.954306Z","steps":["trace[1876711256] 'agreement among raft nodes before linearized reading' (duration: 3.915590907s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:22.954897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:19.038227Z","time spent":"3.916653766s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-21T02:32:23.259016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.328394ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-21T02:32:23.259294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.95396Z","time spent":"305.327634ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-21T02:32:23.259569Z","caller":"traceutil/trace.go:171","msg":"trace[2065266121] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"303.991084ms","start":"2026-08-21T02:32:22.955559Z","end":"2026-08-21T02:32:23.25955Z","steps":["trace[2065266121] 'process raft request' (duration: 303.552164ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:23.259601Z","caller":"traceutil/trace.go:171","msg":"trace[824087500] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"301.870447ms","start":"2026-08-21T02:32:22.957717Z","end":"2026-08-21T02:32:23.259587Z","steps":["trace[824087500] 'process raft request' (duration: 301.807234ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:23.259636Z","caller":"traceutil/trace.go:171","msg":"trace[583011479] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"302.665363ms","start":"2026-08-21T02:32:22.95694Z","end":"2026-08-21T02:32:23.259605Z","steps":["trace[583011479] 'process raft request' (duration: 302.529697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.259697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.955518Z","time spent":"304.103709ms","remote":"127.0.0.1:56568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:23.259781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.957691Z","time spent":"301.93995ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:23.259799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.956922Z","time spent":"302.782439ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:23.26002Z","caller":"traceutil/trace.go:171","msg":"trace[103942484] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"300.691714ms","start":"2026-08-21T02:32:22.959318Z","end":"2026-08-21T02:32:23.26001Z","steps":["trace[103942484] 'process raft request' (duration: 300.237793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.260074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.959306Z","time spent":"300.740355ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:23.260054Z","caller":"traceutil/trace.go:171","msg":"trace[1353072713] linearizableReadLoop","detail":"{readStateIndex:2298; appliedIndex:2292; }","duration":"298.233401ms","start":"2026-08-21T02:32:22.961799Z","end":"2026-08-21T02:32:23.260033Z","steps":["trace[1353072713] 'read index received' (duration: 168.001756ms)","trace[1353072713] 'applied index is now lower than readState.Index' (duration: 130.230705ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:23.260217Z","caller":"traceutil/trace.go:171","msg":"trace[1843215822] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"300.157659ms","start":"2026-08-21T02:32:22.960046Z","end":"2026-08-21T02:32:23.260203Z","steps":["trace[1843215822] 'process raft request' (duration: 299.718599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:23.260388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.039713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:32:23.260414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:22.960021Z","time spent":"300.228503ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:23.260453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.649161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:23.260509Z","caller":"traceutil/trace.go:171","msg":"trace[741273026] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2206; }","duration":"298.722923ms","start":"2026-08-21T02:32:22.961774Z","end":"2026-08-21T02:32:23.260497Z","steps":["trace[741273026] 'agreement among raft nodes before linearized reading' (duration: 298.63824ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:23.260459Z","caller":"traceutil/trace.go:171","msg":"trace[2058989240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2206; }","duration":"298.176949ms","start":"2026-08-21T02:32:22.962266Z","end":"2026-08-21T02:32:23.260443Z","steps":["trace[2058989240] 'agreement among raft nodes before linearized reading' (duration: 298.036553ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:24.724873Z","caller":"traceutil/trace.go:171","msg":"trace[977040363] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"151.99661ms","start":"2026-08-21T02:32:24.572792Z","end":"2026-08-21T02:32:24.724789Z","steps":["trace[977040363] 'process raft request' (duration: 151.859734ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:25.175241Z","caller":"traceutil/trace.go:171","msg":"trace[1146942961] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"221.32903ms","start":"2026-08-21T02:32:24.953886Z","end":"2026-08-21T02:32:25.175215Z","steps":["trace[1146942961] 'process raft request' (duration: 162.021421ms)","trace[1146942961] 'compare' (duration: 58.919602ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:25.175409Z","caller":"traceutil/trace.go:171","msg":"trace[1577827690] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"220.693861ms","start":"2026-08-21T02:32:24.954704Z","end":"2026-08-21T02:32:25.175398Z","steps":["trace[1577827690] 'process raft request' (duration: 220.4478ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:25.175462Z","caller":"traceutil/trace.go:171","msg":"trace[2143842770] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2304; }","duration":"204.927887ms","start":"2026-08-21T02:32:24.970515Z","end":"2026-08-21T02:32:25.175442Z","steps":["trace[2143842770] 'read index received' (duration: 145.349484ms)","trace[2143842770] 'applied index is now lower than readState.Index' (duration: 59.577463ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:25.175493Z","caller":"traceutil/trace.go:171","msg":"trace[1984133489] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"215.856387ms","start":"2026-08-21T02:32:24.959627Z","end":"2026-08-21T02:32:25.175483Z","steps":["trace[1984133489] 'process raft request' (duration: 215.774642ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:25.175498Z","caller":"traceutil/trace.go:171","msg":"trace[2139851970] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"219.562379ms","start":"2026-08-21T02:32:24.95591Z","end":"2026-08-21T02:32:25.175473Z","steps":["trace[2139851970] 'process raft request' (duration: 219.411841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:25.175672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.138817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-21T02:32:25.175706Z","caller":"traceutil/trace.go:171","msg":"trace[1568352428] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2215; }","duration":"205.202411ms","start":"2026-08-21T02:32:24.970493Z","end":"2026-08-21T02:32:25.175695Z","steps":["trace[1568352428] 'agreement among raft nodes before linearized reading' (duration: 205.057083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:25.599797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.476966ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:25.599935Z","caller":"traceutil/trace.go:171","msg":"trace[1108866920] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2220; }","duration":"104.641214ms","start":"2026-08-21T02:32:25.495273Z","end":"2026-08-21T02:32:25.599915Z","steps":["trace[1108866920] 'range keys from in-memory index tree' (duration: 104.452655ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:26.408889Z","caller":"traceutil/trace.go:171","msg":"trace[586073011] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"124.671334ms","start":"2026-08-21T02:32:26.284192Z","end":"2026-08-21T02:32:26.408863Z","steps":["trace[586073011] 'process raft request' (duration: 124.503176ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:26.68586Z","caller":"traceutil/trace.go:171","msg":"trace[2082340792] linearizableReadLoop","detail":"{readStateIndex:2315; appliedIndex:2314; }","duration":"103.928538ms","start":"2026-08-21T02:32:26.581897Z","end":"2026-08-21T02:32:26.685825Z","steps":["trace[2082340792] 'read index received' (duration: 7.37931ms)","trace[2082340792] 'applied index is now lower than readState.Index' (duration: 96.547988ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:26.686167Z","caller":"traceutil/trace.go:171","msg":"trace[1366145350] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"213.686053ms","start":"2026-08-21T02:32:26.472434Z","end":"2026-08-21T02:32:26.68612Z","steps":["trace[1366145350] 'process raft request' (duration: 116.932296ms)","trace[1366145350] 'compare' (duration: 96.289556ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:32:26.686196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.276844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:26.6864Z","caller":"traceutil/trace.go:171","msg":"trace[1807521850] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2222; }","duration":"104.524507ms","start":"2026-08-21T02:32:26.581845Z","end":"2026-08-21T02:32:26.68637Z","steps":["trace[1807521850] 'agreement among raft nodes before linearized reading' (duration: 104.236313ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:27.135868Z","caller":"traceutil/trace.go:171","msg":"trace[1354505129] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"112.248124ms","start":"2026-08-21T02:32:27.02358Z","end":"2026-08-21T02:32:27.135828Z","steps":["trace[1354505129] 'process raft request' (duration: 111.859886ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:27.320656Z","caller":"traceutil/trace.go:171","msg":"trace[564167357] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"138.42261ms","start":"2026-08-21T02:32:27.18221Z","end":"2026-08-21T02:32:27.320632Z","steps":["trace[564167357] 'process raft request' (duration: 138.242841ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:27.32072Z","caller":"traceutil/trace.go:171","msg":"trace[1976149747] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2316; }","duration":"130.829966ms","start":"2026-08-21T02:32:27.189861Z","end":"2026-08-21T02:32:27.320691Z","steps":["trace[1976149747] 'read index received' (duration: 33.254765ms)","trace[1976149747] 'applied index is now lower than readState.Index' (duration: 97.573581ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:32:27.320947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.299941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-21T02:32:27.321007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.136599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-21T02:32:27.321009Z","caller":"traceutil/trace.go:171","msg":"trace[1303892965] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2227; }","duration":"105.408815ms","start":"2026-08-21T02:32:27.215584Z","end":"2026-08-21T02:32:27.320993Z","steps":["trace[1303892965] 'agreement among raft nodes before linearized reading' (duration: 105.274019ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:27.321061Z","caller":"traceutil/trace.go:171","msg":"trace[1267737140] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2227; }","duration":"131.225615ms","start":"2026-08-21T02:32:27.189822Z","end":"2026-08-21T02:32:27.321047Z","steps":["trace[1267737140] 'agreement among raft nodes before linearized reading' (duration: 131.019415ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:27.321021Z","caller":"traceutil/trace.go:171","msg":"trace[310207398] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"137.267114ms","start":"2026-08-21T02:32:27.183644Z","end":"2026-08-21T02:32:27.320911Z","steps":["trace[310207398] 'process raft request' (duration: 136.857734ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:27.321268Z","caller":"traceutil/trace.go:171","msg":"trace[459724974] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"139.787535ms","start":"2026-08-21T02:32:27.181461Z","end":"2026-08-21T02:32:27.321248Z","steps":["trace[459724974] 'process raft request' (duration: 41.624826ms)","trace[459724974] 'compare' (duration: 97.127769ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:27.321638Z","caller":"traceutil/trace.go:171","msg":"trace[1053738175] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"139.800776ms","start":"2026-08-21T02:32:27.181801Z","end":"2026-08-21T02:32:27.321602Z","steps":["trace[1053738175] 'process raft request' (duration: 138.557237ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:53.398445Z","caller":"traceutil/trace.go:171","msg":"trace[1329870908] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"112.146185ms","start":"2026-08-21T02:32:53.286274Z","end":"2026-08-21T02:32:53.39842Z","steps":["trace[1329870908] 'process raft request' (duration: 111.987796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:53.982791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11464370394983693880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:54.483962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11464370394983693880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:54.984643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11464370394983693880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:55.485553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11464370394983693880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:55.986407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11464370394983693880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:56.486983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11464370394983693880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:56.987382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11464370394983693880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:57.09708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999844685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-21T02:32:57.097134Z","caller":"traceutil/trace.go:171","msg":"trace[1333240124] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999962822s","start":"2026-08-21T02:32:55.097159Z","end":"2026-08-21T02:32:57.097122Z","steps":["trace[1333240124] 'agreement among raft nodes before linearized reading' (duration: 1.999888908s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:57.097202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.097127Z","time spent":"2.000066317s","remote":"127.0.0.1:56496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 02:32:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T02:32:57.488578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11464370394983693880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:57.989863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11464370394983693880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:32:58.213182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.718213963s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:58.213428Z","caller":"traceutil/trace.go:171","msg":"trace[125969145] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2468; }","duration":"2.718453218s","start":"2026-08-21T02:32:55.494936Z","end":"2026-08-21T02:32:58.213389Z","steps":["trace[125969145] 'range keys from in-memory index tree' (duration: 2.718193042s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.238164Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.808940652s","expected-duration":"1s"} {"level":"info","ts":"2026-08-21T02:32:58.238564Z","caller":"traceutil/trace.go:171","msg":"trace[1101014198] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"4.80940016s","start":"2026-08-21T02:32:53.429139Z","end":"2026-08-21T02:32:58.238539Z","steps":["trace[1101014198] 'process raft request' (duration: 4.809257201s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.238709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.42912Z","time spent":"4.809516206s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:58.431259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.431511Z","time spent":"4.999742563s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/21 02:32:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T02:32:58.435513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.436182Z","time spent":"4.999324855s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/21 02:32:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T02:32:58.436907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.437752Z","time spent":"4.999150206s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"info","ts":"2026-08-21T02:32:58.46357Z","caller":"traceutil/trace.go:171","msg":"trace[313874064] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"4.965503388s","start":"2026-08-21T02:32:53.49805Z","end":"2026-08-21T02:32:58.463553Z","steps":["trace[313874064] 'process raft request' (duration: 4.965446804s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.463693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.498032Z","time spent":"4.965580312s","remote":"127.0.0.1:53950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2481,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-21T02:32:58.463767Z","caller":"traceutil/trace.go:171","msg":"trace[765850095] transaction","detail":"{read_only:false; response_revision:2474; number_of_response:1; }","duration":"4.90456637s","start":"2026-08-21T02:32:53.559175Z","end":"2026-08-21T02:32:58.463742Z","steps":["trace[765850095] 'process raft request' (duration: 4.904351258s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.46385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.55915Z","time spent":"4.904654865s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:58.480742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998628719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T02:32:58.480846Z","caller":"traceutil/trace.go:171","msg":"trace[408744247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.998878063s","start":"2026-08-21T02:32:53.481943Z","end":"2026-08-21T02:32:58.480821Z","steps":["trace[408744247] 'agreement among raft nodes before linearized reading' (duration: 4.99866571s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.480912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.481922Z","time spent":"4.99897249s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/21 02:32:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T02:32:58.488824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99949795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T02:32:58.488861Z","caller":"traceutil/trace.go:171","msg":"trace[979350227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999561275s","start":"2026-08-21T02:32:53.489291Z","end":"2026-08-21T02:32:58.488852Z","steps":["trace[979350227] 'agreement among raft nodes before linearized reading' (duration: 4.999519362s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.488897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.489281Z","time spent":"4.999610377s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/08/21 02:32:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T02:32:58.491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999915478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-21T02:32:58.491103Z","caller":"traceutil/trace.go:171","msg":"trace[1663690702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000022424s","start":"2026-08-21T02:32:53.491029Z","end":"2026-08-21T02:32:58.491051Z","steps":["trace[1663690702] 'agreement among raft nodes before linearized reading' (duration: 4.999930279s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.491192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.491019Z","time spent":"5.000148191s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-21T02:32:58.61Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.258149ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-21T02:32:58.610325Z","caller":"traceutil/trace.go:171","msg":"trace[387126335] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"4.397123801s","start":"2026-08-21T02:32:54.213183Z","end":"2026-08-21T02:32:58.610307Z","steps":["trace[387126335] 'process raft request' (duration: 4.396914558s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.610446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:54.213158Z","time spent":"4.397208575s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:58.610587Z","caller":"traceutil/trace.go:171","msg":"trace[426481881] linearizableReadLoop","detail":"{readStateIndex:2576; appliedIndex:2567; }","duration":"5.128560105s","start":"2026-08-21T02:32:53.481995Z","end":"2026-08-21T02:32:58.610555Z","steps":["trace[426481881] 'read index received' (duration: 4.756462202s)","trace[426481881] 'applied index is now lower than readState.Index' (duration: 372.096433ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:58.610756Z","caller":"traceutil/trace.go:171","msg":"trace[1251155133] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"3.230905295s","start":"2026-08-21T02:32:55.379828Z","end":"2026-08-21T02:32:58.610733Z","steps":["trace[1251155133] 'process raft request' (duration: 3.230439757s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.610865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.3798Z","time spent":"3.23100861s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:58.611115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847442476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:58.61115Z","caller":"traceutil/trace.go:171","msg":"trace[90672604] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2477; }","duration":"1.847515879s","start":"2026-08-21T02:32:56.763624Z","end":"2026-08-21T02:32:58.61114Z","steps":["trace[90672604] 'agreement among raft nodes before linearized reading' (duration: 1.847437165s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.763603Z","time spent":"1.847561653s","remote":"127.0.0.1:44456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:58.611199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.405802326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-08-21T02:32:58.611255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.992499732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:32:58.611308Z","caller":"traceutil/trace.go:171","msg":"trace[1698673244] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2477; }","duration":"1.992541945s","start":"2026-08-21T02:32:56.618748Z","end":"2026-08-21T02:32:58.611289Z","steps":["trace[1698673244] 'agreement among raft nodes before linearized reading' (duration: 1.992482271s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.611319Z","caller":"traceutil/trace.go:171","msg":"trace[477056340] transaction","detail":"{read_only:false; response_revision:2477; number_of_response:1; }","duration":"138.026504ms","start":"2026-08-21T02:32:58.473285Z","end":"2026-08-21T02:32:58.611311Z","steps":["trace[477056340] 'process raft request' (duration: 137.63595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.618692Z","time spent":"1.992651281s","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:58.611377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.79182645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-08-21T02:32:58.611395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.209343752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-21T02:32:58.61143Z","caller":"traceutil/trace.go:171","msg":"trace[1106312898] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2477; }","duration":"3.791912825s","start":"2026-08-21T02:32:54.819501Z","end":"2026-08-21T02:32:58.611413Z","steps":["trace[1106312898] 'agreement among raft nodes before linearized reading' (duration: 3.791785938s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.61144Z","caller":"traceutil/trace.go:171","msg":"trace[1334386820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2477; }","duration":"3.209406094s","start":"2026-08-21T02:32:55.402017Z","end":"2026-08-21T02:32:58.611423Z","steps":["trace[1334386820] 'agreement among raft nodes before linearized reading' (duration: 3.209313129s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.368349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-21T02:32:58.611474Z","caller":"traceutil/trace.go:171","msg":"trace[1339317334] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2477; }","duration":"172.428203ms","start":"2026-08-21T02:32:58.439037Z","end":"2026-08-21T02:32:58.611465Z","steps":["trace[1339317334] 'agreement among raft nodes before linearized reading' (duration: 172.35875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.402006Z","time spent":"3.209461678s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-21T02:32:58.611477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:54.819471Z","time spent":"3.791993758s","remote":"127.0.0.1:56706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-21T02:32:58.611573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.10249571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:58.611613Z","caller":"traceutil/trace.go:171","msg":"trace[2002816114] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2477; }","duration":"2.102573015s","start":"2026-08-21T02:32:56.50903Z","end":"2026-08-21T02:32:58.611603Z","steps":["trace[2002816114] 'agreement among raft nodes before linearized reading' (duration: 2.102516531s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.61164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.509015Z","time spent":"2.102618988s","remote":"127.0.0.1:48460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-08-21T02:32:58.611257Z","caller":"traceutil/trace.go:171","msg":"trace[393976908] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2477; }","duration":"3.40590051s","start":"2026-08-21T02:32:55.205343Z","end":"2026-08-21T02:32:58.611243Z","steps":["trace[393976908] 'agreement among raft nodes before linearized reading' (duration: 3.405748002s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.205314Z","time spent":"3.406362418s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-21T02:32:58.611723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.241066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-21T02:32:58.611309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.113149166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:58.611762Z","caller":"traceutil/trace.go:171","msg":"trace[941467483] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2477; }","duration":"178.314651ms","start":"2026-08-21T02:32:58.433437Z","end":"2026-08-21T02:32:58.611752Z","steps":["trace[941467483] 'agreement among raft nodes before linearized reading' (duration: 178.236536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.186884662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-21T02:32:58.611782Z","caller":"traceutil/trace.go:171","msg":"trace[12069698] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2477; }","duration":"3.113654117s","start":"2026-08-21T02:32:55.498118Z","end":"2026-08-21T02:32:58.611772Z","steps":["trace[12069698] 'agreement among raft nodes before linearized reading' (duration: 3.113163208s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.611805Z","caller":"traceutil/trace.go:171","msg":"trace[1510358904] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2477; }","duration":"2.186934855s","start":"2026-08-21T02:32:56.424863Z","end":"2026-08-21T02:32:58.611798Z","steps":["trace[1510358904] 'agreement among raft nodes before linearized reading' (duration: 2.186866862s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.498092Z","time spent":"3.113713289s","remote":"127.0.0.1:48578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:58.611826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.424846Z","time spent":"2.186976878s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-21T02:32:58.611924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.65541142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-21T02:32:58.611949Z","caller":"traceutil/trace.go:171","msg":"trace[839942716] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2477; }","duration":"2.655466453s","start":"2026-08-21T02:32:55.956476Z","end":"2026-08-21T02:32:58.611942Z","steps":["trace[839942716] 'agreement among raft nodes before linearized reading' (duration: 2.655404469s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514337994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:58.611989Z","caller":"traceutil/trace.go:171","msg":"trace[1683011206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2477; }","duration":"1.514390276s","start":"2026-08-21T02:32:57.097589Z","end":"2026-08-21T02:32:58.611979Z","steps":["trace[1683011206] 'agreement among raft nodes before linearized reading' (duration: 1.514353464s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.612005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.673036934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-21T02:32:58.611245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.123890117s","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-08-21T02:32:58.612022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:57.097573Z","time spent":"1.514443869s","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T02:32:58.611922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.198457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.131\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-21T02:32:58.612055Z","caller":"traceutil/trace.go:171","msg":"trace[807143951] range","detail":"{range_begin:/registry/masterleases/199.19.213.131; range_end:; response_count:1; response_revision:2477; }","duration":"184.368348ms","start":"2026-08-21T02:32:58.427682Z","end":"2026-08-21T02:32:58.612051Z","steps":["trace[807143951] 'agreement among raft nodes before linearized reading' (duration: 184.181486ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.612049Z","caller":"traceutil/trace.go:171","msg":"trace[1567776583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2477; }","duration":"5.124751168s","start":"2026-08-21T02:32:53.487286Z","end":"2026-08-21T02:32:58.612038Z","steps":["trace[1567776583] 'agreement among raft nodes before linearized reading' (duration: 5.123738578s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.612089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:53.487276Z","time spent":"5.124802591s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-21T02:32:58.611585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.783399083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:32:58.61158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.731191812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:32:58.612143Z","caller":"traceutil/trace.go:171","msg":"trace[626686914] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2477; }","duration":"1.783980507s","start":"2026-08-21T02:32:56.828151Z","end":"2026-08-21T02:32:58.612131Z","steps":["trace[626686914] 'agreement among raft nodes before linearized reading' (duration: 1.783416894s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.956454Z","time spent":"2.655508055s","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-21T02:32:58.612163Z","caller":"traceutil/trace.go:171","msg":"trace[2006438661] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2477; }","duration":"1.73182474s","start":"2026-08-21T02:32:56.880329Z","end":"2026-08-21T02:32:58.612153Z","steps":["trace[2006438661] 'agreement among raft nodes before linearized reading' (duration: 1.731219894s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.148093083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-21T02:32:58.612181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.828131Z","time spent":"1.78403842s","remote":"127.0.0.1:44368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:58.612196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.880308Z","time spent":"1.731878592s","remote":"127.0.0.1:56996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-08-21T02:32:58.612206Z","caller":"traceutil/trace.go:171","msg":"trace[589580264] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2477; }","duration":"4.148591542s","start":"2026-08-21T02:32:54.463606Z","end":"2026-08-21T02:32:58.612197Z","steps":["trace[589580264] 'agreement among raft nodes before linearized reading' (duration: 4.148070561s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.61223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:54.463587Z","time spent":"4.148634945s","remote":"127.0.0.1:56540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:58.611657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.899869ms","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-08-21T02:32:58.612322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716986032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:58.612325Z","caller":"traceutil/trace.go:171","msg":"trace[1695894823] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2477; }","duration":"175.60601ms","start":"2026-08-21T02:32:58.43671Z","end":"2026-08-21T02:32:58.612316Z","steps":["trace[1695894823] 'agreement among raft nodes before linearized reading' (duration: 174.887508ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.612021Z","caller":"traceutil/trace.go:171","msg":"trace[860331083] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2477; }","duration":"2.673082197s","start":"2026-08-21T02:32:55.938934Z","end":"2026-08-21T02:32:58.612016Z","steps":["trace[860331083] 'agreement among raft nodes before linearized reading' (duration: 2.673043765s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.611183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.933317559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:32:58.612574Z","caller":"traceutil/trace.go:171","msg":"trace[1467838974] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2477; }","duration":"2.934753874s","start":"2026-08-21T02:32:55.6778Z","end":"2026-08-21T02:32:58.612554Z","steps":["trace[1467838974] 'agreement among raft nodes before linearized reading' (duration: 2.933275277s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.612637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.677787Z","time spent":"2.934825807s","remote":"127.0.0.1:55564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:58.612412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:55.938915Z","time spent":"2.673480651s","remote":"127.0.0.1:53950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":19,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-08-21T02:32:58.615085Z","caller":"traceutil/trace.go:171","msg":"trace[1676715733] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2477; }","duration":"1.717017334s","start":"2026-08-21T02:32:56.895317Z","end":"2026-08-21T02:32:58.612335Z","steps":["trace[1676715733] 'agreement among raft nodes before linearized reading' (duration: 1.716989682s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.615146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:56.895308Z","time spent":"1.719813009s","remote":"127.0.0.1:56924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:32:58.98287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.010501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:32:58.983161Z","caller":"traceutil/trace.go:171","msg":"trace[1785720858] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"363.260642ms","start":"2026-08-21T02:32:58.61982Z","end":"2026-08-21T02:32:58.983081Z","steps":["trace[1785720858] 'process raft request' (duration: 363.194868ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.983184Z","caller":"traceutil/trace.go:171","msg":"trace[2001319239] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"363.345477ms","start":"2026-08-21T02:32:58.619794Z","end":"2026-08-21T02:32:58.983139Z","steps":["trace[2001319239] 'process raft request' (duration: 363.172018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.983241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.619817Z","time spent":"363.38957ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:58.983343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.619789Z","time spent":"363.462314ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:58.983341Z","caller":"traceutil/trace.go:171","msg":"trace[150704345] transaction","detail":"{read_only:false; response_revision:2478; number_of_response:1; }","duration":"363.596412ms","start":"2026-08-21T02:32:58.619722Z","end":"2026-08-21T02:32:58.983318Z","steps":["trace[150704345] 'process raft request' (duration: 253.055661ms)","trace[150704345] 'compare' (duration: 109.898143ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:58.983509Z","caller":"traceutil/trace.go:171","msg":"trace[1204547100] transaction","detail":"{read_only:false; response_revision:2481; number_of_response:1; }","duration":"363.393901ms","start":"2026-08-21T02:32:58.620088Z","end":"2026-08-21T02:32:58.983482Z","steps":["trace[1204547100] 'process raft request' (duration: 362.955095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.983643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.619714Z","time spent":"363.812246ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:58.983655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.620082Z","time spent":"363.494086ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:32:58.984012Z","caller":"traceutil/trace.go:171","msg":"trace[476923264] linearizableReadLoop","detail":"{readStateIndex:2582; appliedIndex:2578; }","duration":"319.771416ms","start":"2026-08-21T02:32:58.664226Z","end":"2026-08-21T02:32:58.983997Z","steps":["trace[476923264] 'read index received' (duration: 208.723494ms)","trace[476923264] 'applied index is now lower than readState.Index' (duration: 111.045402ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:32:58.984173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.305635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-21T02:32:58.984229Z","caller":"traceutil/trace.go:171","msg":"trace[627184067] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2481; }","duration":"364.368838ms","start":"2026-08-21T02:32:58.619846Z","end":"2026-08-21T02:32:58.984215Z","steps":["trace[627184067] 'agreement among raft nodes before linearized reading' (duration: 364.192268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.984245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.618674ms","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-08-21T02:32:58.984269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.619842Z","time spent":"364.418961ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-21T02:32:58.984282Z","caller":"traceutil/trace.go:171","msg":"trace[549126614] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2481; }","duration":"364.678487ms","start":"2026-08-21T02:32:58.61959Z","end":"2026-08-21T02:32:58.984269Z","steps":["trace[549126614] 'agreement among raft nodes before linearized reading' (duration: 364.529577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.984314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.619577Z","time spent":"364.730438ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-08-21T02:32:58.984413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.38008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:58.984447Z","caller":"traceutil/trace.go:171","msg":"trace[1970723560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2481; }","duration":"362.432353ms","start":"2026-08-21T02:32:58.622006Z","end":"2026-08-21T02:32:58.984438Z","steps":["trace[1970723560] 'agreement among raft nodes before linearized reading' (duration: 362.38161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.984479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.621995Z","time spent":"362.478327ms","remote":"127.0.0.1:56496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T02:32:58.984598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.988234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-21T02:32:58.984649Z","caller":"traceutil/trace.go:171","msg":"trace[1813070046] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2481; }","duration":"365.044487ms","start":"2026-08-21T02:32:58.619584Z","end":"2026-08-21T02:32:58.984628Z","steps":["trace[1813070046] 'agreement among raft nodes before linearized reading' (duration: 364.944731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.984685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.619572Z","time spent":"365.105092ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-21T02:32:58.984725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.849547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cdb146603ea46f\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-08-21T02:32:58.984577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.124701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-21T02:32:58.984852Z","caller":"traceutil/trace.go:171","msg":"trace[1740284468] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2481; }","duration":"367.423168ms","start":"2026-08-21T02:32:58.617415Z","end":"2026-08-21T02:32:58.984838Z","steps":["trace[1740284468] 'agreement among raft nodes before linearized reading' (duration: 366.749438ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:58.984868Z","caller":"traceutil/trace.go:171","msg":"trace[894719219] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18cdb146603ea46f; range_end:; response_count:1; response_revision:2481; }","duration":"364.90625ms","start":"2026-08-21T02:32:58.619848Z","end":"2026-08-21T02:32:58.984754Z","steps":["trace[894719219] 'agreement among raft nodes before linearized reading' (duration: 364.802353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.984966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.619838Z","time spent":"365.112021ms","remote":"127.0.0.1:56568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cdb146603ea46f\" "} {"level":"warn","ts":"2026-08-21T02:32:58.985056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.617402Z","time spent":"367.532515ms","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-21T02:32:58.98663Z","caller":"traceutil/trace.go:171","msg":"trace[1463352222] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"321.241513ms","start":"2026-08-21T02:32:58.665375Z","end":"2026-08-21T02:32:58.986617Z","steps":["trace[1463352222] 'process raft request' (duration: 317.863213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.986691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.665344Z","time spent":"321.316007ms","remote":"127.0.0.1:56520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:58.986903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.32119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1313"} {"level":"info","ts":"2026-08-21T02:32:58.98693Z","caller":"traceutil/trace.go:171","msg":"trace[2020406468] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2482; }","duration":"319.379425ms","start":"2026-08-21T02:32:58.667545Z","end":"2026-08-21T02:32:58.986924Z","steps":["trace[2020406468] 'agreement among raft nodes before linearized reading' (duration: 319.270748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:58.986949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:58.667534Z","time spent":"319.410496ms","remote":"127.0.0.1:56706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1337,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-08-21T02:33:01.222862Z","caller":"traceutil/trace.go:171","msg":"trace[699996235] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"232.394364ms","start":"2026-08-21T02:33:00.990449Z","end":"2026-08-21T02:33:01.222843Z","steps":["trace[699996235] 'process raft request' (duration: 232.332681ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:01.222894Z","caller":"traceutil/trace.go:171","msg":"trace[736311965] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"232.669032ms","start":"2026-08-21T02:33:00.990192Z","end":"2026-08-21T02:33:01.222861Z","steps":["trace[736311965] 'process raft request' (duration: 206.136085ms)","trace[736311965] 'compare' (duration: 26.352926ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:01.223021Z","caller":"traceutil/trace.go:171","msg":"trace[1394335717] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"230.396226ms","start":"2026-08-21T02:33:00.992617Z","end":"2026-08-21T02:33:01.223013Z","steps":["trace[1394335717] 'process raft request' (duration: 230.192824ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:01.223193Z","caller":"traceutil/trace.go:171","msg":"trace[1530534073] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"230.007754ms","start":"2026-08-21T02:33:00.993178Z","end":"2026-08-21T02:33:01.223186Z","steps":["trace[1530534073] 'process raft request' (duration: 229.807372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:01.614369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.024972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-08-21T02:33:01.614528Z","caller":"traceutil/trace.go:171","msg":"trace[646390599] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2493; }","duration":"105.238354ms","start":"2026-08-21T02:33:01.50927Z","end":"2026-08-21T02:33:01.614508Z","steps":["trace[646390599] 'range keys from in-memory index tree' (duration: 104.799068ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:02.792932Z","caller":"traceutil/trace.go:171","msg":"trace[29491356] transaction","detail":"{read_only:false; response_revision:2497; number_of_response:1; }","duration":"130.83533ms","start":"2026-08-21T02:33:02.662068Z","end":"2026-08-21T02:33:02.792904Z","steps":["trace[29491356] 'process raft request' (duration: 84.531248ms)","trace[29491356] 'compare' (duration: 46.195085ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:33:03.31676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.892811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:33:03.316855Z","caller":"traceutil/trace.go:171","msg":"trace[1602514760] linearizableReadLoop","detail":"{readStateIndex:2599; appliedIndex:2598; }","duration":"220.28357ms","start":"2026-08-21T02:33:03.096555Z","end":"2026-08-21T02:33:03.316839Z","steps":["trace[1602514760] 'read index received' (duration: 47.106022ms)","trace[1602514760] 'applied index is now lower than readState.Index' (duration: 173.176358ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:33:03.317197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.607439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:33:03.317334Z","caller":"traceutil/trace.go:171","msg":"trace[887167508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2498; }","duration":"220.756418ms","start":"2026-08-21T02:33:03.096513Z","end":"2026-08-21T02:33:03.317269Z","steps":["trace[887167508] 'agreement among raft nodes before linearized reading' (duration: 220.578657ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:03.317142Z","caller":"traceutil/trace.go:171","msg":"trace[1090096342] transaction","detail":"{read_only:false; response_revision:2498; number_of_response:1; }","duration":"279.276128ms","start":"2026-08-21T02:33:03.03785Z","end":"2026-08-21T02:33:03.317127Z","steps":["trace[1090096342] 'process raft request' (duration: 105.91595ms)","trace[1090096342] 'compare' (duration: 172.789174ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:03.339003Z","caller":"traceutil/trace.go:171","msg":"trace[864181188] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"110.991005ms","start":"2026-08-21T02:33:03.227996Z","end":"2026-08-21T02:33:03.338987Z","steps":["trace[864181188] 'process raft request' (duration: 110.879199ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:03.353063Z","caller":"traceutil/trace.go:171","msg":"trace[37988895] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"124.707304ms","start":"2026-08-21T02:33:03.228318Z","end":"2026-08-21T02:33:03.353025Z","steps":["trace[37988895] 'process raft request' (duration: 124.47917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:03.353153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.337472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-21T02:33:03.353299Z","caller":"traceutil/trace.go:171","msg":"trace[1253643241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2500; }","duration":"125.582116ms","start":"2026-08-21T02:33:03.227703Z","end":"2026-08-21T02:33:03.353286Z","steps":["trace[1253643241] 'agreement among raft nodes before linearized reading' (duration: 125.284578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:03.353578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.753225ms","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-08-21T02:33:03.353655Z","caller":"traceutil/trace.go:171","msg":"trace[1363007562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2500; }","duration":"125.862292ms","start":"2026-08-21T02:33:03.227775Z","end":"2026-08-21T02:33:03.353637Z","steps":["trace[1363007562] 'agreement among raft nodes before linearized reading' (duration: 125.634078ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:03.499264Z","caller":"traceutil/trace.go:171","msg":"trace[75684655] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"141.606632ms","start":"2026-08-21T02:33:03.357628Z","end":"2026-08-21T02:33:03.499235Z","steps":["trace[75684655] 'process raft request' (duration: 60.715163ms)","trace[75684655] 'compare' (duration: 80.781303ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:04.511526Z","caller":"traceutil/trace.go:171","msg":"trace[743163936] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"186.997898ms","start":"2026-08-21T02:33:04.324509Z","end":"2026-08-21T02:33:04.511507Z","steps":["trace[743163936] 'process raft request' (duration: 186.871201ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:04.511511Z","caller":"traceutil/trace.go:171","msg":"trace[2115531234] linearizableReadLoop","detail":"{readStateIndex:2604; appliedIndex:2603; }","duration":"439.684436ms","start":"2026-08-21T02:33:04.071792Z","end":"2026-08-21T02:33:04.511477Z","steps":["trace[2115531234] 'read index received' (duration: 353.307533ms)","trace[2115531234] 'applied index is now lower than readState.Index' (duration: 86.373073ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:33:04.511822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.018187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-21T02:33:04.511813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.689918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:33:04.511907Z","caller":"traceutil/trace.go:171","msg":"trace[164057006] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2503; }","duration":"440.138714ms","start":"2026-08-21T02:33:04.071735Z","end":"2026-08-21T02:33:04.511874Z","steps":["trace[164057006] 'agreement among raft nodes before linearized reading' (duration: 439.831705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:04.511974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:33:04.071714Z","time spent":"440.249841ms","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-21T02:33:04.511999Z","caller":"traceutil/trace.go:171","msg":"trace[1959907311] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2503; }","duration":"285.847417ms","start":"2026-08-21T02:33:04.226037Z","end":"2026-08-21T02:33:04.511885Z","steps":["trace[1959907311] 'agreement among raft nodes before linearized reading' (duration: 285.694187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:07.054474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.510293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:33:07.054583Z","caller":"traceutil/trace.go:171","msg":"trace[1269796704] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"176.179283ms","start":"2026-08-21T02:33:06.87838Z","end":"2026-08-21T02:33:07.054559Z","steps":["trace[1269796704] 'process raft request' (duration: 26.502149ms)","trace[1269796704] 'compare' (duration: 149.323012ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:08.797047Z","caller":"traceutil/trace.go:171","msg":"trace[2017463046] transaction","detail":"{read_only:false; response_revision:2525; number_of_response:1; }","duration":"109.966129ms","start":"2026-08-21T02:33:08.687057Z","end":"2026-08-21T02:33:08.797023Z","steps":["trace[2017463046] 'process raft request' (duration: 109.861763ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:27.087846Z","caller":"traceutil/trace.go:171","msg":"trace[1184107137] transaction","detail":"{read_only:false; response_revision:2626; number_of_response:1; }","duration":"427.51442ms","start":"2026-08-21T02:33:26.660305Z","end":"2026-08-21T02:33:27.087819Z","steps":["trace[1184107137] 'process raft request' (duration: 427.36182ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:27.087884Z","caller":"traceutil/trace.go:171","msg":"trace[2118583732] linearizableReadLoop","detail":"{readStateIndex:2733; appliedIndex:2733; }","duration":"400.843419ms","start":"2026-08-21T02:33:26.687006Z","end":"2026-08-21T02:33:27.087849Z","steps":["trace[2118583732] 'read index received' (duration: 400.831639ms)","trace[2118583732] 'applied index is now lower than readState.Index' (duration: 9.16µs)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:33:27.088061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:33:26.660281Z","time spent":"427.648769ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:33:27.088418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.129855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-21T02:33:27.088464Z","caller":"traceutil/trace.go:171","msg":"trace[1166303912] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2626; }","duration":"269.208781ms","start":"2026-08-21T02:33:26.819243Z","end":"2026-08-21T02:33:27.088451Z","steps":["trace[1166303912] 'agreement among raft nodes before linearized reading' (duration: 269.044491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:27.088467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:33:27.088555Z","caller":"traceutil/trace.go:171","msg":"trace[1747087595] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2626; }","duration":"231.741737ms","start":"2026-08-21T02:33:26.856794Z","end":"2026-08-21T02:33:27.088536Z","steps":["trace[1747087595] 'agreement among raft nodes before linearized reading' (duration: 231.599427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:27.089028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.574334ms","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-08-21T02:33:27.089138Z","caller":"traceutil/trace.go:171","msg":"trace[181061801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2626; }","duration":"166.731964ms","start":"2026-08-21T02:33:26.92238Z","end":"2026-08-21T02:33:27.089112Z","steps":["trace[181061801] 'agreement among raft nodes before linearized reading' (duration: 166.291465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:27.088323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.286518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-21T02:33:27.089583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.310569ms","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-08-21T02:33:27.089707Z","caller":"traceutil/trace.go:171","msg":"trace[841536081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2626; }","duration":"237.47013ms","start":"2026-08-21T02:33:26.852218Z","end":"2026-08-21T02:33:27.089688Z","steps":["trace[841536081] 'agreement among raft nodes before linearized reading' (duration: 236.214448ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:27.09042Z","caller":"traceutil/trace.go:171","msg":"trace[483787669] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2626; }","duration":"402.544261ms","start":"2026-08-21T02:33:26.686954Z","end":"2026-08-21T02:33:27.089499Z","steps":["trace[483787669] 'agreement among raft nodes before linearized reading' (duration: 401.271077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:27.090522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:33:26.686932Z","time spent":"403.550927ms","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-08-21T02:33:37.676157Z","caller":"traceutil/trace.go:171","msg":"trace[1910215605] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"107.930427ms","start":"2026-08-21T02:33:37.5682Z","end":"2026-08-21T02:33:37.676131Z","steps":["trace[1910215605] 'process raft request' (duration: 75.899959ms)","trace[1910215605] 'compare' (duration: 31.736167ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:37.813664Z","caller":"traceutil/trace.go:171","msg":"trace[867260703] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"133.778639ms","start":"2026-08-21T02:33:37.679843Z","end":"2026-08-21T02:33:37.813621Z","steps":["trace[867260703] 'process raft request' (duration: 122.752749ms)","trace[867260703] 'compare' (duration: 10.787923ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:43.497192Z","caller":"traceutil/trace.go:171","msg":"trace[195121972] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"268.558645ms","start":"2026-08-21T02:33:43.228603Z","end":"2026-08-21T02:33:43.497162Z","steps":["trace[195121972] 'process raft request' (duration: 246.302404ms)","trace[195121972] 'compare' (duration: 21.78348ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:54.157758Z","caller":"traceutil/trace.go:171","msg":"trace[817513320] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"431.08325ms","start":"2026-08-21T02:33:53.726648Z","end":"2026-08-21T02:33:54.157732Z","steps":["trace[817513320] 'process raft request' (duration: 430.860905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:54.157933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:33:53.726626Z","time spent":"431.210238ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:33:54.158078Z","caller":"traceutil/trace.go:171","msg":"trace[1825069964] linearizableReadLoop","detail":"{readStateIndex:2885; appliedIndex:2885; }","duration":"294.777325ms","start":"2026-08-21T02:33:53.863149Z","end":"2026-08-21T02:33:54.157927Z","steps":["trace[1825069964] 'read index received' (duration: 294.770053ms)","trace[1825069964] 'applied index is now lower than readState.Index' (duration: 5.831µs)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:33:54.158419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.835799ms","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-08-21T02:33:54.158489Z","caller":"traceutil/trace.go:171","msg":"trace[1980621653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2772; }","duration":"268.958758ms","start":"2026-08-21T02:33:53.889513Z","end":"2026-08-21T02:33:54.158472Z","steps":["trace[1980621653] 'agreement among raft nodes before linearized reading' (duration: 268.665138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:54.158596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.446961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:33:54.158644Z","caller":"traceutil/trace.go:171","msg":"trace[841327276] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2772; }","duration":"295.535776ms","start":"2026-08-21T02:33:53.863098Z","end":"2026-08-21T02:33:54.158634Z","steps":["trace[841327276] 'agreement among raft nodes before linearized reading' (duration: 295.44567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:54.159201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.483187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-21T02:33:54.159249Z","caller":"traceutil/trace.go:171","msg":"trace[387748096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2772; }","duration":"265.701242ms","start":"2026-08-21T02:33:53.893537Z","end":"2026-08-21T02:33:54.159238Z","steps":["trace[387748096] 'agreement among raft nodes before linearized reading' (duration: 265.454165ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:16.416292Z","caller":"traceutil/trace.go:171","msg":"trace[229453616] linearizableReadLoop","detail":"{readStateIndex:4729; appliedIndex:4728; }","duration":"108.523122ms","start":"2026-08-21T02:37:16.307736Z","end":"2026-08-21T02:37:16.416259Z","steps":["trace[229453616] 'read index received' (duration: 20.053241ms)","trace[229453616] 'applied index is now lower than readState.Index' (duration: 88.468321ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:37:16.41691Z","caller":"traceutil/trace.go:171","msg":"trace[1095268060] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"176.625389ms","start":"2026-08-21T02:37:16.240247Z","end":"2026-08-21T02:37:16.416873Z","steps":["trace[1095268060] 'process raft request' (duration: 87.635279ms)","trace[1095268060] 'compare' (duration: 87.91367ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:37:16.417473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.721628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-08-21T02:37:16.417511Z","caller":"traceutil/trace.go:171","msg":"trace[1572824277] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4549; }","duration":"109.81451ms","start":"2026-08-21T02:37:16.307685Z","end":"2026-08-21T02:37:16.4175Z","steps":["trace[1572824277] 'agreement among raft nodes before linearized reading' (duration: 108.801002ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:21.629599Z","caller":"traceutil/trace.go:171","msg":"trace[626177551] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"105.271874ms","start":"2026-08-21T02:37:21.524299Z","end":"2026-08-21T02:37:21.629571Z","steps":["trace[626177551] 'process raft request' (duration: 105.123339ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:21.705945Z","caller":"traceutil/trace.go:171","msg":"trace[393665695] linearizableReadLoop","detail":"{readStateIndex:4856; appliedIndex:4854; }","duration":"159.839822ms","start":"2026-08-21T02:37:21.546047Z","end":"2026-08-21T02:37:21.705887Z","steps":["trace[393665695] 'read index received' (duration: 83.382451ms)","trace[393665695] 'applied index is now lower than readState.Index' (duration: 76.455851ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:37:21.706171Z","caller":"traceutil/trace.go:171","msg":"trace[1809218933] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"160.719187ms","start":"2026-08-21T02:37:21.54543Z","end":"2026-08-21T02:37:21.706149Z","steps":["trace[1809218933] 'process raft request' (duration: 133.556828ms)","trace[1809218933] 'compare' (duration: 26.558085ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:37:21.70621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.137654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-523ba1cd-e9d0-4c71-90f8-9a6a72e9cbaa\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-21T02:37:21.706284Z","caller":"traceutil/trace.go:171","msg":"trace[1786093207] range","detail":"{range_begin:/registry/persistentvolumes/pvc-523ba1cd-e9d0-4c71-90f8-9a6a72e9cbaa; range_end:; response_count:1; response_revision:4675; }","duration":"160.263049ms","start":"2026-08-21T02:37:21.546005Z","end":"2026-08-21T02:37:21.706268Z","steps":["trace[1786093207] 'agreement among raft nodes before linearized reading' (duration: 160.063751ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:21.706094Z","caller":"traceutil/trace.go:171","msg":"trace[858128312] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"151.765407ms","start":"2026-08-21T02:37:21.554244Z","end":"2026-08-21T02:37:21.70601Z","steps":["trace[858128312] 'process raft request' (duration: 151.560259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:37:21.723705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.293621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-08-21T02:37:21.723834Z","caller":"traceutil/trace.go:171","msg":"trace[149774144] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4676; }","duration":"149.430646ms","start":"2026-08-21T02:37:21.574353Z","end":"2026-08-21T02:37:21.723784Z","steps":["trace[149774144] 'agreement among raft nodes before linearized reading' (duration: 148.912166ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:21.829644Z","caller":"traceutil/trace.go:171","msg":"trace[48984394] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"116.255533ms","start":"2026-08-21T02:37:21.713356Z","end":"2026-08-21T02:37:21.829612Z","steps":["trace[48984394] 'process raft request' (duration: 113.209715ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:21.830287Z","caller":"traceutil/trace.go:171","msg":"trace[196109914] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"116.834186ms","start":"2026-08-21T02:37:21.713435Z","end":"2026-08-21T02:37:21.830269Z","steps":["trace[196109914] 'process raft request' (duration: 116.055786ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:21.830381Z","caller":"traceutil/trace.go:171","msg":"trace[467872843] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"116.831436ms","start":"2026-08-21T02:37:21.713497Z","end":"2026-08-21T02:37:21.830328Z","steps":["trace[467872843] 'process raft request' (duration: 116.467891ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:21.830458Z","caller":"traceutil/trace.go:171","msg":"trace[1290255934] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"116.846016ms","start":"2026-08-21T02:37:21.71357Z","end":"2026-08-21T02:37:21.830416Z","steps":["trace[1290255934] 'process raft request' (duration: 116.640178ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:35.462679Z","caller":"traceutil/trace.go:171","msg":"trace[1061838138] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"183.609191ms","start":"2026-08-21T02:37:35.279023Z","end":"2026-08-21T02:37:35.462632Z","steps":["trace[1061838138] 'process raft request' (duration: 183.459354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:37:35.596744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.803447ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:37:35.596838Z","caller":"traceutil/trace.go:171","msg":"trace[1244749460] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4863; }","duration":"101.918092ms","start":"2026-08-21T02:37:35.494906Z","end":"2026-08-21T02:37:35.596824Z","steps":["trace[1244749460] 'range keys from in-memory index tree' (duration: 101.771735ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:35.789865Z","caller":"traceutil/trace.go:171","msg":"trace[647622045] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5051; }","duration":"309.457578ms","start":"2026-08-21T02:37:35.480381Z","end":"2026-08-21T02:37:35.789838Z","steps":["trace[647622045] 'read index received' (duration: 309.227138ms)","trace[647622045] 'applied index is now lower than readState.Index' (duration: 229.14µs)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:37:35.790138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.16988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16552"} {"level":"info","ts":"2026-08-21T02:37:35.790213Z","caller":"traceutil/trace.go:171","msg":"trace[168325077] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4864; }","duration":"176.288146ms","start":"2026-08-21T02:37:35.613914Z","end":"2026-08-21T02:37:35.790202Z","steps":["trace[168325077] 'agreement among raft nodes before linearized reading' (duration: 176.095927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:37:35.790279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.895596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-08-21T02:37:35.790402Z","caller":"traceutil/trace.go:171","msg":"trace[621081558] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4864; }","duration":"310.068263ms","start":"2026-08-21T02:37:35.480292Z","end":"2026-08-21T02:37:35.79036Z","steps":["trace[621081558] 'agreement among raft nodes before linearized reading' (duration: 309.745399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:37:35.790514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:37:35.480265Z","time spent":"310.23122ms","remote":"127.0.0.1:48440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3340,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-08-21T02:37:35.789963Z","caller":"traceutil/trace.go:171","msg":"trace[1556908390] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"508.212029ms","start":"2026-08-21T02:37:35.281725Z","end":"2026-08-21T02:37:35.789937Z","steps":["trace[1556908390] 'process raft request' (duration: 507.955958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:37:35.790669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:37:35.2817Z","time spent":"508.890636ms","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:37:36.051672Z","caller":"traceutil/trace.go:171","msg":"trace[188952918] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"120.099576ms","start":"2026-08-21T02:37:35.931552Z","end":"2026-08-21T02:37:36.051651Z","steps":["trace[188952918] 'process raft request' (duration: 85.02712ms)","trace[188952918] 'compare' (duration: 34.768393ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:38:05.166842Z","caller":"traceutil/trace.go:171","msg":"trace[1889498467] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"127.583253ms","start":"2026-08-21T02:38:05.039231Z","end":"2026-08-21T02:38:05.166814Z","steps":["trace[1889498467] 'process raft request' (duration: 127.122432ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:39:04.897291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2828} {"level":"info","ts":"2026-08-21T02:39:04.967449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2828,"took":"68.707469ms","hash":464452858,"current-db-size-bytes":54472704,"current-db-size":"54 MB","current-db-size-in-use-bytes":29728768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-21T02:39:04.967508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":464452858,"revision":2828,"compact-revision":-1} {"level":"info","ts":"2026-08-21T02:44:04.903304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5592} {"level":"info","ts":"2026-08-21T02:44:04.987281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5592,"took":"82.388911ms","hash":3308751758,"current-db-size-bytes":54472704,"current-db-size":"54 MB","current-db-size-in-use-bytes":18436096,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-21T02:44:04.987349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3308751758,"revision":5592,"compact-revision":2828}