{"level":"warn","ts":"2026-10-19T01:52:53.825063Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-19T01:52:53.825203Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.102: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://162.253.55.102:2380","--initial-cluster=instance=https://162.253.55.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.102: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-10-19T01:52:53.825379Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-19T01:52:53.825398Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.102:2380"]} {"level":"info","ts":"2026-10-19T01:52:53.825435Z","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-10-19T01:52:53.826878Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.102:2379"]} {"level":"info","ts":"2026-10-19T01:52:53.827019Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.102:2380"],"listen-peer-urls":["https://162.253.55.102:2380"],"advertise-client-urls":["https://162.253.55.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.102: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-10-19T01:52:53.832372Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.973927ms"} {"level":"info","ts":"2026-10-19T01:52:53.840981Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2af340d70e332c3","cluster-id":"da2a6dcf93591a68"} {"level":"info","ts":"2026-10-19T01:52:53.841098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-19T01:52:53.841131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became follower at term 0"} {"level":"info","ts":"2026-10-19T01:52:53.84115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2af340d70e332c3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-19T01:52:53.841173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became follower at term 1"} {"level":"info","ts":"2026-10-19T01:52:53.841234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 switched to configuration voters=(16334331605829235395)"} {"level":"warn","ts":"2026-10-19T01:52:53.848321Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-19T01:52:53.850356Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-19T01:52:53.851998Z","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-10-19T01:52:53.855401Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2af340d70e332c3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-19T01:52:53.855634Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2af340d70e332c3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-19T01:52:53.855813Z","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-10-19T01:52:53.855962Z","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-10-19T01:52:53.856015Z","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-10-19T01:52:53.856921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 switched to configuration voters=(16334331605829235395)"} {"level":"info","ts":"2026-10-19T01:52:53.857112Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"da2a6dcf93591a68","local-member-id":"e2af340d70e332c3","added-peer-id":"e2af340d70e332c3","added-peer-peer-urls":["https://162.253.55.102:2380"]} {"level":"info","ts":"2026-10-19T01:52:53.862026Z","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-10-19T01:52:53.862437Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2af340d70e332c3","initial-advertise-peer-urls":["https://162.253.55.102:2380"],"listen-peer-urls":["https://162.253.55.102:2380"],"advertise-client-urls":["https://162.253.55.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-19T01:52:53.862474Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-19T01:52:53.86263Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.102:2380"} {"level":"info","ts":"2026-10-19T01:52:53.862649Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.102:2380"} {"level":"info","ts":"2026-10-19T01:52:54.442464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-19T01:52:54.442555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-19T01:52:54.442625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 received MsgPreVoteResp from e2af340d70e332c3 at term 1"} {"level":"info","ts":"2026-10-19T01:52:54.44265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became candidate at term 2"} {"level":"info","ts":"2026-10-19T01:52:54.442662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 received MsgVoteResp from e2af340d70e332c3 at term 2"} {"level":"info","ts":"2026-10-19T01:52:54.442679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2af340d70e332c3 became leader at term 2"} {"level":"info","ts":"2026-10-19T01:52:54.442701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2af340d70e332c3 elected leader e2af340d70e332c3 at term 2"} {"level":"info","ts":"2026-10-19T01:52:54.444159Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2af340d70e332c3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.102:2379]}","request-path":"/0/members/e2af340d70e332c3/attributes","cluster-id":"da2a6dcf93591a68","publish-timeout":"7s"} {"level":"info","ts":"2026-10-19T01:52:54.444357Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-19T01:52:54.444431Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-19T01:52:54.444976Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:52:54.445228Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-19T01:52:54.445306Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-19T01:52:54.447823Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"da2a6dcf93591a68","local-member-id":"e2af340d70e332c3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:52:54.447926Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:52:54.447995Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:52:54.448117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-19T01:52:54.455054Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.102:2379"} {"level":"info","ts":"2026-10-19T01:53:42.414971Z","caller":"traceutil/trace.go:171","msg":"trace[1660915037] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"143.482938ms","start":"2026-10-19T01:53:42.271453Z","end":"2026-10-19T01:53:42.414936Z","steps":["trace[1660915037] 'process raft request' (duration: 143.297634ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:42.414944Z","caller":"traceutil/trace.go:171","msg":"trace[683743111] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"143.55565ms","start":"2026-10-19T01:53:42.271339Z","end":"2026-10-19T01:53:42.414895Z","steps":["trace[683743111] 'process raft request' (duration: 80.586279ms)","trace[683743111] 'compare' (duration: 62.554041ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:53:42.414952Z","caller":"traceutil/trace.go:171","msg":"trace[160942363] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"141.668101ms","start":"2026-10-19T01:53:42.273261Z","end":"2026-10-19T01:53:42.414929Z","steps":["trace[160942363] 'process raft request' (duration: 141.578339ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:42.42191Z","caller":"traceutil/trace.go:171","msg":"trace[2124429260] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"136.984393ms","start":"2026-10-19T01:53:42.284906Z","end":"2026-10-19T01:53:42.42189Z","steps":["trace[2124429260] 'process raft request' (duration: 136.695776ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:42.422188Z","caller":"traceutil/trace.go:171","msg":"trace[2080589731] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"137.27054ms","start":"2026-10-19T01:53:42.284906Z","end":"2026-10-19T01:53:42.422176Z","steps":["trace[2080589731] 'process raft request' (duration: 136.887131ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:42.422572Z","caller":"traceutil/trace.go:171","msg":"trace[502231132] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"130.006344ms","start":"2026-10-19T01:53:42.292529Z","end":"2026-10-19T01:53:42.422535Z","steps":["trace[502231132] 'process raft request' (duration: 129.493161ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:50.294234Z","caller":"traceutil/trace.go:171","msg":"trace[1066152453] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"226.929809ms","start":"2026-10-19T01:53:50.067261Z","end":"2026-10-19T01:53:50.294191Z","steps":["trace[1066152453] 'read index received' (duration: 226.689543ms)","trace[1066152453] 'applied index is now lower than readState.Index' (duration: 238.216µs)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:53:50.294455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.139863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:53:50.294522Z","caller":"traceutil/trace.go:171","msg":"trace[1647772323] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"287.988061ms","start":"2026-10-19T01:53:50.006367Z","end":"2026-10-19T01:53:50.294355Z","steps":["trace[1647772323] 'process raft request' (duration: 287.631493ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:50.294565Z","caller":"traceutil/trace.go:171","msg":"trace[1393965052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"227.285267ms","start":"2026-10-19T01:53:50.067254Z","end":"2026-10-19T01:53:50.294539Z","steps":["trace[1393965052] 'agreement among raft nodes before linearized reading' (duration: 227.080382ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:50.500441Z","caller":"traceutil/trace.go:171","msg":"trace[1103570450] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"306.348879ms","start":"2026-10-19T01:53:50.194062Z","end":"2026-10-19T01:53:50.50041Z","steps":["trace[1103570450] 'process raft request' (duration: 305.290661ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:50.500399Z","caller":"traceutil/trace.go:171","msg":"trace[83857835] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"204.112939ms","start":"2026-10-19T01:53:50.29623Z","end":"2026-10-19T01:53:50.500342Z","steps":["trace[83857835] 'read index received' (duration: 202.60405ms)","trace[83857835] 'applied index is now lower than readState.Index' (duration: 1.507729ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:53:50.500669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.408037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:53:50.500754Z","caller":"traceutil/trace.go:171","msg":"trace[1680037346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"204.570211ms","start":"2026-10-19T01:53:50.296159Z","end":"2026-10-19T01:53:50.500729Z","steps":["trace[1680037346] 'agreement among raft nodes before linearized reading' (duration: 204.393306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:53:50.501198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:53:50.194025Z","time spent":"306.486242ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:53:50.733638Z","caller":"traceutil/trace.go:171","msg":"trace[691588941] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"145.852728ms","start":"2026-10-19T01:53:50.587747Z","end":"2026-10-19T01:53:50.733599Z","steps":["trace[691588941] 'process raft request' (duration: 118.368738ms)","trace[691588941] 'compare' (duration: 27.288935ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:53:51.397342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.380123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-10-19T01:53:51.397454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.110052ms","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-10-19T01:53:51.397671Z","caller":"traceutil/trace.go:171","msg":"trace[1927662744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:658; }","duration":"413.378369ms","start":"2026-10-19T01:53:50.98424Z","end":"2026-10-19T01:53:51.397618Z","steps":["trace[1927662744] 'range keys from in-memory index tree' (duration: 412.853385ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:51.397488Z","caller":"traceutil/trace.go:171","msg":"trace[1844149350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"386.576297ms","start":"2026-10-19T01:53:51.010881Z","end":"2026-10-19T01:53:51.397457Z","steps":["trace[1844149350] 'range keys from in-memory index tree' (duration: 386.139248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:53:51.397863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:53:51.010872Z","time spent":"386.974677ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-19T01:53:51.397948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.002559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-10-19T01:53:51.398038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:53:50.984209Z","time spent":"413.506232ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-19T01:53:51.397495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.05698ms","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-10-19T01:53:51.398353Z","caller":"traceutil/trace.go:171","msg":"trace[1458160183] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:658; }","duration":"387.950033ms","start":"2026-10-19T01:53:51.010367Z","end":"2026-10-19T01:53:51.398317Z","steps":["trace[1458160183] 'range keys from in-memory index tree' (duration: 387.019358ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:51.398515Z","caller":"traceutil/trace.go:171","msg":"trace[560871679] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"325.523705ms","start":"2026-10-19T01:53:51.072822Z","end":"2026-10-19T01:53:51.398345Z","steps":["trace[560871679] 'process raft request' (duration: 114.731546ms)","trace[560871679] 'compare' (duration: 209.746442ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:53:51.398685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:53:51.072792Z","time spent":"325.786232ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:53:51.399605Z","caller":"traceutil/trace.go:171","msg":"trace[1234182310] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"252.337015ms","start":"2026-10-19T01:53:51.147243Z","end":"2026-10-19T01:53:51.39958Z","steps":["trace[1234182310] 'process raft request' (duration: 251.117344ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:54:07.682293Z","caller":"traceutil/trace.go:171","msg":"trace[1406427461] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"187.075646ms","start":"2026-10-19T01:54:07.495187Z","end":"2026-10-19T01:54:07.682263Z","steps":["trace[1406427461] 'process raft request' (duration: 186.911623ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:54:07.83632Z","caller":"traceutil/trace.go:171","msg":"trace[155019831] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:853; }","duration":"327.573017ms","start":"2026-10-19T01:54:07.508706Z","end":"2026-10-19T01:54:07.836279Z","steps":["trace[155019831] 'read index received' (duration: 173.492671ms)","trace[155019831] 'applied index is now lower than readState.Index' (duration: 154.079026ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:54:07.836618Z","caller":"traceutil/trace.go:171","msg":"trace[2042755765] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"333.304894ms","start":"2026-10-19T01:54:07.503105Z","end":"2026-10-19T01:54:07.83641Z","steps":["trace[2042755765] 'process raft request' (duration: 327.272681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:54:07.836799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:54:07.503072Z","time spent":"333.610592ms","remote":"127.0.0.1:49694","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-10-19T01:54:07.836823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.116392ms","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-10-19T01:54:07.836867Z","caller":"traceutil/trace.go:171","msg":"trace[386792963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"328.218023ms","start":"2026-10-19T01:54:07.508633Z","end":"2026-10-19T01:54:07.836851Z","steps":["trace[386792963] 'agreement among raft nodes before linearized reading' (duration: 328.058189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:54:07.836905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:54:07.50862Z","time spent":"328.277294ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-19T01:54:07.8376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.603019ms","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-10-19T01:54:07.83834Z","caller":"traceutil/trace.go:171","msg":"trace[1827687403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:824; }","duration":"258.445031ms","start":"2026-10-19T01:54:07.579854Z","end":"2026-10-19T01:54:07.838299Z","steps":["trace[1827687403] 'agreement among raft nodes before linearized reading' (duration: 257.464295ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:54:11.521637Z","caller":"traceutil/trace.go:171","msg":"trace[983963163] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"124.500954ms","start":"2026-10-19T01:54:11.397095Z","end":"2026-10-19T01:54:11.521596Z","steps":["trace[983963163] 'process raft request' (duration: 70.738828ms)","trace[983963163] 'compare' (duration: 53.406207ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:55:28.144866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.098313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-19T01:55:28.145044Z","caller":"traceutil/trace.go:171","msg":"trace[945294260] range","detail":"{range_begin:/registry/masterleases/162.253.55.102; range_end:; response_count:1; response_revision:1905; }","duration":"269.363129ms","start":"2026-10-19T01:55:27.875652Z","end":"2026-10-19T01:55:28.145015Z","steps":["trace[945294260] 'range keys from in-memory index tree' (duration: 268.798655ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:55:28.439223Z","caller":"traceutil/trace.go:171","msg":"trace[1545516945] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"260.203848ms","start":"2026-10-19T01:55:28.178977Z","end":"2026-10-19T01:55:28.439181Z","steps":["trace[1545516945] 'process raft request' (duration: 207.0892ms)","trace[1545516945] 'compare' (duration: 52.827841ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:55:28.445646Z","caller":"traceutil/trace.go:171","msg":"trace[1664948404] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1981; }","duration":"185.027374ms","start":"2026-10-19T01:55:28.260592Z","end":"2026-10-19T01:55:28.44562Z","steps":["trace[1664948404] 'read index received' (duration: 125.527034ms)","trace[1664948404] 'applied index is now lower than readState.Index' (duration: 59.49951ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:55:28.445714Z","caller":"traceutil/trace.go:171","msg":"trace[278622001] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"263.03284ms","start":"2026-10-19T01:55:28.182626Z","end":"2026-10-19T01:55:28.445659Z","steps":["trace[278622001] 'process raft request' (duration: 262.758723ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:55:28.44577Z","caller":"traceutil/trace.go:171","msg":"trace[2145597896] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"261.067451ms","start":"2026-10-19T01:55:28.18463Z","end":"2026-10-19T01:55:28.445698Z","steps":["trace[2145597896] 'process raft request' (duration: 260.940348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:55:28.445998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.367412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:55:28.446096Z","caller":"traceutil/trace.go:171","msg":"trace[1200390212] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1908; }","duration":"185.524296ms","start":"2026-10-19T01:55:28.260541Z","end":"2026-10-19T01:55:28.446065Z","steps":["trace[1200390212] 'agreement among raft nodes before linearized reading' (duration: 185.28357ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:55:28.706946Z","caller":"traceutil/trace.go:171","msg":"trace[1798575010] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"251.46549ms","start":"2026-10-19T01:55:28.455448Z","end":"2026-10-19T01:55:28.706913Z","steps":["trace[1798575010] 'process raft request' (duration: 160.608969ms)","trace[1798575010] 'compare' (duration: 90.653445ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:55:28.707336Z","caller":"traceutil/trace.go:171","msg":"trace[1015347705] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"251.393257ms","start":"2026-10-19T01:55:28.455924Z","end":"2026-10-19T01:55:28.707317Z","steps":["trace[1015347705] 'process raft request' (duration: 250.941016ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:55:28.707388Z","caller":"traceutil/trace.go:171","msg":"trace[513470921] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"195.696882ms","start":"2026-10-19T01:55:28.511641Z","end":"2026-10-19T01:55:28.707337Z","steps":["trace[513470921] 'process raft request' (duration: 195.563839ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:55:28.707462Z","caller":"traceutil/trace.go:171","msg":"trace[1121782150] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1985; }","duration":"205.565841ms","start":"2026-10-19T01:55:28.501835Z","end":"2026-10-19T01:55:28.707401Z","steps":["trace[1121782150] 'read index received' (duration: 114.331562ms)","trace[1121782150] 'applied index is now lower than readState.Index' (duration: 91.223699ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:55:28.707837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.974752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-19T01:55:28.707881Z","caller":"traceutil/trace.go:171","msg":"trace[1048282467] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1912; }","duration":"206.096465ms","start":"2026-10-19T01:55:28.501768Z","end":"2026-10-19T01:55:28.707865Z","steps":["trace[1048282467] 'agreement among raft nodes before linearized reading' (duration: 205.803397ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:55:44.934393Z","caller":"traceutil/trace.go:171","msg":"trace[1264754601] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"126.468509ms","start":"2026-10-19T01:55:44.807895Z","end":"2026-10-19T01:55:44.934363Z","steps":["trace[1264754601] 'process raft request' (duration: 126.295925ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:55:44.953581Z","caller":"traceutil/trace.go:171","msg":"trace[924759785] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"144.422235ms","start":"2026-10-19T01:55:44.809119Z","end":"2026-10-19T01:55:44.953541Z","steps":["trace[924759785] 'process raft request' (duration: 144.089627ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:55:44.953778Z","caller":"traceutil/trace.go:171","msg":"trace[536421174] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"116.208068ms","start":"2026-10-19T01:55:44.837515Z","end":"2026-10-19T01:55:44.953724Z","steps":["trace[536421174] 'process raft request' (duration: 115.89197ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:56:18.815733Z","caller":"traceutil/trace.go:171","msg":"trace[1935985277] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"124.610273ms","start":"2026-10-19T01:56:18.69109Z","end":"2026-10-19T01:56:18.8157Z","steps":["trace[1935985277] 'process raft request' (duration: 124.389877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:56:19.229185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.438119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T01:56:19.229481Z","caller":"traceutil/trace.go:171","msg":"trace[1193357556] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2314; }","duration":"162.485276ms","start":"2026-10-19T01:56:19.066969Z","end":"2026-10-19T01:56:19.229454Z","steps":["trace[1193357556] 'read index received' (duration: 64.371258ms)","trace[1193357556] 'applied index is now lower than readState.Index' (duration: 98.112138ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:56:19.229467Z","caller":"traceutil/trace.go:171","msg":"trace[311983570] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"407.907633ms","start":"2026-10-19T01:56:18.821522Z","end":"2026-10-19T01:56:19.22943Z","steps":["trace[311983570] 'process raft request' (duration: 103.108075ms)","trace[311983570] 'compare' (duration: 304.131731ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:56:19.229512Z","caller":"traceutil/trace.go:171","msg":"trace[668836853] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"407.912403ms","start":"2026-10-19T01:56:18.821555Z","end":"2026-10-19T01:56:19.229467Z","steps":["trace[668836853] 'process raft request' (duration: 407.78705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:56:19.229667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:56:18.82149Z","time spent":"408.066327ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T01:56:19.229734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.769254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:56:19.22984Z","caller":"traceutil/trace.go:171","msg":"trace[2144296021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2229; }","duration":"162.928438ms","start":"2026-10-19T01:56:19.066892Z","end":"2026-10-19T01:56:19.229821Z","steps":["trace[2144296021] 'agreement among raft nodes before linearized reading' (duration: 162.787994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:56:19.229746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:56:18.821506Z","time spent":"408.125419ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:56:19.25786Z","caller":"traceutil/trace.go:171","msg":"trace[2139333428] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"162.787984ms","start":"2026-10-19T01:56:19.095047Z","end":"2026-10-19T01:56:19.257835Z","steps":["trace[2139333428] 'process raft request' (duration: 162.552278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:56:19.258575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.779011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-10-19T01:56:19.259505Z","caller":"traceutil/trace.go:171","msg":"trace[1679696974] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2231; }","duration":"104.770686ms","start":"2026-10-19T01:56:19.154712Z","end":"2026-10-19T01:56:19.259483Z","steps":["trace[1679696974] 'agreement among raft nodes before linearized reading' (duration: 103.72064ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:56:19.258567Z","caller":"traceutil/trace.go:171","msg":"trace[1956011969] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"161.919432ms","start":"2026-10-19T01:56:19.096611Z","end":"2026-10-19T01:56:19.25853Z","steps":["trace[1956011969] 'process raft request' (duration: 161.140743ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:07.598603Z","caller":"traceutil/trace.go:171","msg":"trace[427635622] transaction","detail":"{read_only:false; response_revision:2665; number_of_response:1; }","duration":"103.004729ms","start":"2026-10-19T01:57:07.495563Z","end":"2026-10-19T01:57:07.598567Z","steps":["trace[427635622] 'process raft request' (duration: 102.817634ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:08.110346Z","caller":"traceutil/trace.go:171","msg":"trace[945627435] linearizableReadLoop","detail":"{readStateIndex:2772; appliedIndex:2771; }","duration":"166.335415ms","start":"2026-10-19T01:57:07.943977Z","end":"2026-10-19T01:57:08.110313Z","steps":["trace[945627435] 'read index received' (duration: 166.116389ms)","trace[945627435] 'applied index is now lower than readState.Index' (duration: 217.356µs)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:57:08.11059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.599721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-19T01:57:08.110649Z","caller":"traceutil/trace.go:171","msg":"trace[1319500032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2668; }","duration":"166.725165ms","start":"2026-10-19T01:57:07.943909Z","end":"2026-10-19T01:57:08.110635Z","steps":["trace[1319500032] 'agreement among raft nodes before linearized reading' (duration: 166.52355ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:41.935492Z","caller":"traceutil/trace.go:171","msg":"trace[1931125868] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"139.000581ms","start":"2026-10-19T01:57:41.796464Z","end":"2026-10-19T01:57:41.935465Z","steps":["trace[1931125868] 'process raft request' (duration: 138.847487ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:41.959219Z","caller":"traceutil/trace.go:171","msg":"trace[1309708677] linearizableReadLoop","detail":"{readStateIndex:2980; appliedIndex:2978; }","duration":"158.773183ms","start":"2026-10-19T01:57:41.800416Z","end":"2026-10-19T01:57:41.959189Z","steps":["trace[1309708677] 'read index received' (duration: 134.908817ms)","trace[1309708677] 'applied index is now lower than readState.Index' (duration: 23.862845ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:57:41.959292Z","caller":"traceutil/trace.go:171","msg":"trace[1574510469] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"158.895595ms","start":"2026-10-19T01:57:41.800352Z","end":"2026-10-19T01:57:41.959248Z","steps":["trace[1574510469] 'process raft request' (duration: 158.672999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:41.959555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.07137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-19T01:57:41.959605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.640978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-19T01:57:41.95965Z","caller":"traceutil/trace.go:171","msg":"trace[929804020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2870; }","duration":"159.218984ms","start":"2026-10-19T01:57:41.800412Z","end":"2026-10-19T01:57:41.959631Z","steps":["trace[929804020] 'agreement among raft nodes before linearized reading' (duration: 158.923156ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:41.959665Z","caller":"traceutil/trace.go:171","msg":"trace[992976027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2870; }","duration":"124.74376ms","start":"2026-10-19T01:57:41.834905Z","end":"2026-10-19T01:57:41.959649Z","steps":["trace[992976027] 'agreement among raft nodes before linearized reading' (duration: 124.459453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:41.959698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.163153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-19T01:57:41.960136Z","caller":"traceutil/trace.go:171","msg":"trace[1036949742] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2870; }","duration":"114.406909ms","start":"2026-10-19T01:57:41.845477Z","end":"2026-10-19T01:57:41.959884Z","steps":["trace[1036949742] 'agreement among raft nodes before linearized reading' (duration: 113.935387ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:42.183597Z","caller":"traceutil/trace.go:171","msg":"trace[714021720] linearizableReadLoop","detail":"{readStateIndex:2983; appliedIndex:2980; }","duration":"117.62887ms","start":"2026-10-19T01:57:42.065947Z","end":"2026-10-19T01:57:42.183576Z","steps":["trace[714021720] 'read index received' (duration: 111.778532ms)","trace[714021720] 'applied index is now lower than readState.Index' (duration: 5.849548ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:57:42.183679Z","caller":"traceutil/trace.go:171","msg":"trace[1946454461] transaction","detail":"{read_only:false; response_revision:2871; number_of_response:1; }","duration":"218.29545ms","start":"2026-10-19T01:57:41.965366Z","end":"2026-10-19T01:57:42.183662Z","steps":["trace[1946454461] 'process raft request' (duration: 212.265118ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:42.183711Z","caller":"traceutil/trace.go:171","msg":"trace[488205273] transaction","detail":"{read_only:false; response_revision:2872; number_of_response:1; }","duration":"218.173266ms","start":"2026-10-19T01:57:41.965527Z","end":"2026-10-19T01:57:42.1837Z","steps":["trace[488205273] 'process raft request' (duration: 217.959201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:42.184144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.173643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:42.184285Z","caller":"traceutil/trace.go:171","msg":"trace[339314385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2873; }","duration":"118.317147ms","start":"2026-10-19T01:57:42.065941Z","end":"2026-10-19T01:57:42.184258Z","steps":["trace[339314385] 'agreement among raft nodes before linearized reading' (duration: 118.121003ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:42.184162Z","caller":"traceutil/trace.go:171","msg":"trace[1285988440] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"218.574797ms","start":"2026-10-19T01:57:41.96556Z","end":"2026-10-19T01:57:42.184134Z","steps":["trace[1285988440] 'process raft request' (duration: 217.986363ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:42.18429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.965031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-19T01:57:42.184382Z","caller":"traceutil/trace.go:171","msg":"trace[1901101070] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2873; }","duration":"115.125005ms","start":"2026-10-19T01:57:42.069234Z","end":"2026-10-19T01:57:42.184359Z","steps":["trace[1901101070] 'agreement among raft nodes before linearized reading' (duration: 114.92455ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:00:50.488227Z","caller":"traceutil/trace.go:171","msg":"trace[955998192] transaction","detail":"{read_only:false; response_revision:4066; number_of_response:1; }","duration":"118.001214ms","start":"2026-10-19T02:00:50.370199Z","end":"2026-10-19T02:00:50.4882Z","steps":["trace[955998192] 'process raft request' (duration: 117.816009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:21.102354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.911614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4921"} {"level":"info","ts":"2026-10-19T02:02:21.102531Z","caller":"traceutil/trace.go:171","msg":"trace[1306728245] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5146; }","duration":"110.131369ms","start":"2026-10-19T02:02:20.992372Z","end":"2026-10-19T02:02:21.102503Z","steps":["trace[1306728245] 'range keys from in-memory index tree' (duration: 109.702269ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:02:23.699728Z","caller":"traceutil/trace.go:171","msg":"trace[983659723] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"116.688072ms","start":"2026-10-19T02:02:23.582994Z","end":"2026-10-19T02:02:23.699682Z","steps":["trace[983659723] 'process raft request' (duration: 84.389249ms)","trace[983659723] 'compare' (duration: 32.090098ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:02:23.70013Z","caller":"traceutil/trace.go:171","msg":"trace[556277070] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"116.680332ms","start":"2026-10-19T02:02:23.583411Z","end":"2026-10-19T02:02:23.700091Z","steps":["trace[556277070] 'process raft request' (duration: 116.233221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:23.700503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.893168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-chunks-cache-0\" ","response":"range_response_count:1 size:3421"} {"level":"info","ts":"2026-10-19T02:02:23.700567Z","caller":"traceutil/trace.go:171","msg":"trace[1671691744] range","detail":"{range_begin:/registry/pods/monitoring/loki-chunks-cache-0; range_end:; response_count:1; response_revision:5251; }","duration":"118.97477ms","start":"2026-10-19T02:02:23.581573Z","end":"2026-10-19T02:02:23.700548Z","steps":["trace[1671691744] 'agreement among raft nodes before linearized reading' (duration: 118.698543ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:02:23.701143Z","caller":"traceutil/trace.go:171","msg":"trace[1987646835] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"108.067418ms","start":"2026-10-19T02:02:23.593064Z","end":"2026-10-19T02:02:23.701131Z","steps":["trace[1987646835] 'process raft request' (duration: 106.663763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:25.210675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.327994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T02:02:25.210904Z","caller":"traceutil/trace.go:171","msg":"trace[788155395] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"313.117408ms","start":"2026-10-19T02:02:24.897766Z","end":"2026-10-19T02:02:25.210884Z","steps":["trace[788155395] 'process raft request' (duration: 313.037946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:25.210977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:24.897728Z","time spent":"313.20825ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:02:25.211205Z","caller":"traceutil/trace.go:171","msg":"trace[1502649029] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"315.127158ms","start":"2026-10-19T02:02:24.896064Z","end":"2026-10-19T02:02:25.211192Z","steps":["trace[1502649029] 'process raft request' (duration: 161.173849ms)","trace[1502649029] 'compare' (duration: 153.16077ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:02:25.211284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:24.896037Z","time spent":"315.20135ms","remote":"127.0.0.1:49694","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-10-19T02:02:41.125721Z","caller":"traceutil/trace.go:171","msg":"trace[1363499416] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"117.42138ms","start":"2026-10-19T02:02:41.008263Z","end":"2026-10-19T02:02:41.125684Z","steps":["trace[1363499416] 'process raft request' (duration: 40.427235ms)","trace[1363499416] 'compare' (duration: 76.653127ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:02:41.465668Z","caller":"traceutil/trace.go:171","msg":"trace[1736696575] transaction","detail":"{read_only:false; response_revision:5557; number_of_response:1; }","duration":"334.330565ms","start":"2026-10-19T02:02:41.131295Z","end":"2026-10-19T02:02:41.465625Z","steps":["trace[1736696575] 'process raft request' (duration: 330.047688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:41.465853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:41.131274Z","time spent":"334.485759ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:02:41.468949Z","caller":"traceutil/trace.go:171","msg":"trace[1085981404] linearizableReadLoop","detail":"{readStateIndex:5772; appliedIndex:5767; }","duration":"148.838872ms","start":"2026-10-19T02:02:41.320093Z","end":"2026-10-19T02:02:41.468932Z","steps":["trace[1085981404] 'read index received' (duration: 141.408197ms)","trace[1085981404] 'applied index is now lower than readState.Index' (duration: 7.429455ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:02:41.469233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.14133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-dd959f65-6plnm\" ","response":"range_response_count:1 size:11838"} {"level":"info","ts":"2026-10-19T02:02:41.46929Z","caller":"traceutil/trace.go:171","msg":"trace[2119887015] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-dd959f65-6plnm; range_end:; response_count:1; response_revision:5563; }","duration":"149.267413ms","start":"2026-10-19T02:02:41.320007Z","end":"2026-10-19T02:02:41.469275Z","steps":["trace[2119887015] 'agreement among raft nodes before linearized reading' (duration: 149.001886ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:02:41.469584Z","caller":"traceutil/trace.go:171","msg":"trace[1245798157] transaction","detail":"{read_only:false; response_revision:5558; number_of_response:1; }","duration":"336.990452ms","start":"2026-10-19T02:02:41.132582Z","end":"2026-10-19T02:02:41.469573Z","steps":["trace[1245798157] 'process raft request' (duration: 336.049828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:41.469656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:41.132563Z","time spent":"337.050533ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:02:41.469915Z","caller":"traceutil/trace.go:171","msg":"trace[529796914] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"336.390286ms","start":"2026-10-19T02:02:41.133512Z","end":"2026-10-19T02:02:41.469902Z","steps":["trace[529796914] 'process raft request' (duration: 335.208917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:41.469977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:41.133495Z","time spent":"336.451459ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:02:41.470363Z","caller":"traceutil/trace.go:171","msg":"trace[1722580542] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"336.420866ms","start":"2026-10-19T02:02:41.133918Z","end":"2026-10-19T02:02:41.470339Z","steps":["trace[1722580542] 'process raft request' (duration: 334.853718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:41.470467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:41.133908Z","time spent":"336.508988ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:02:41.47061Z","caller":"traceutil/trace.go:171","msg":"trace[194568436] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"261.410571ms","start":"2026-10-19T02:02:41.209171Z","end":"2026-10-19T02:02:41.470582Z","steps":["trace[194568436] 'process raft request' (duration: 259.640577ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:02:41.470882Z","caller":"traceutil/trace.go:171","msg":"trace[2088031393] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"137.962991ms","start":"2026-10-19T02:02:41.332904Z","end":"2026-10-19T02:02:41.470867Z","steps":["trace[2088031393] 'process raft request' (duration: 135.955381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:50.399288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.06244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T02:02:50.39943Z","caller":"traceutil/trace.go:171","msg":"trace[731368091] linearizableReadLoop","detail":"{readStateIndex:5940; appliedIndex:5939; }","duration":"442.327291ms","start":"2026-10-19T02:02:49.957075Z","end":"2026-10-19T02:02:50.399402Z","steps":["trace[731368091] 'read index received' (duration: 37.371µs)","trace[731368091] 'applied index is now lower than readState.Index' (duration: 442.2883ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:02:50.39955Z","caller":"traceutil/trace.go:171","msg":"trace[663008256] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"520.383762ms","start":"2026-10-19T02:02:49.879152Z","end":"2026-10-19T02:02:50.399536Z","steps":["trace[663008256] 'process raft request' (duration: 49.973153ms)","trace[663008256] 'compare' (duration: 469.931817ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:02:50.399631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:49.879115Z","time spent":"520.471475ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:02:50.400022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.950446ms","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-10-19T02:02:50.400091Z","caller":"traceutil/trace.go:171","msg":"trace[1681664208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5724; }","duration":"443.08778ms","start":"2026-10-19T02:02:49.956989Z","end":"2026-10-19T02:02:50.400076Z","steps":["trace[1681664208] 'agreement among raft nodes before linearized reading' (duration: 442.876655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:50.400133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:49.956967Z","time spent":"443.155092ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-19T02:02:50.400276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.149512ms","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-10-19T02:02:50.400376Z","caller":"traceutil/trace.go:171","msg":"trace[105513448] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5724; }","duration":"404.311466ms","start":"2026-10-19T02:02:49.996044Z","end":"2026-10-19T02:02:50.400356Z","steps":["trace[105513448] 'agreement among raft nodes before linearized reading' (duration: 404.06689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:50.400416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:49.996002Z","time spent":"404.405328ms","remote":"127.0.0.1:49694","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-10-19T02:02:50.400551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.375791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:02:50.400584Z","caller":"traceutil/trace.go:171","msg":"trace[1166870739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5724; }","duration":"333.450223ms","start":"2026-10-19T02:02:50.067125Z","end":"2026-10-19T02:02:50.400575Z","steps":["trace[1166870739] 'agreement among raft nodes before linearized reading' (duration: 333.400041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:50.400606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:50.067091Z","time spent":"333.509844ms","remote":"127.0.0.1:49394","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-10-19T02:02:50.400814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.010855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-19T02:02:50.400874Z","caller":"traceutil/trace.go:171","msg":"trace[763702610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5724; }","duration":"196.113128ms","start":"2026-10-19T02:02:50.204745Z","end":"2026-10-19T02:02:50.400858Z","steps":["trace[763702610] 'agreement among raft nodes before linearized reading' (duration: 195.959834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:50.400829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.908082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5252a3ef-8a77-4aa2-94b8-de86372843ea\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-10-19T02:02:50.400887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.203524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-19T02:02:50.400946Z","caller":"traceutil/trace.go:171","msg":"trace[557597013] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5252a3ef-8a77-4aa2-94b8-de86372843ea; range_end:; response_count:1; response_revision:5724; }","duration":"358.075305ms","start":"2026-10-19T02:02:50.042843Z","end":"2026-10-19T02:02:50.400918Z","steps":["trace[557597013] 'agreement among raft nodes before linearized reading' (duration: 357.728808ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:02:50.400971Z","caller":"traceutil/trace.go:171","msg":"trace[865557910] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5724; }","duration":"238.335557ms","start":"2026-10-19T02:02:50.162608Z","end":"2026-10-19T02:02:50.400944Z","steps":["trace[865557910] 'agreement among raft nodes before linearized reading' (duration: 238.162264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:50.401012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:50.042829Z","time spent":"358.170858ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5252a3ef-8a77-4aa2-94b8-de86372843ea\" "} {"level":"warn","ts":"2026-10-19T02:02:50.400717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.825902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5252a3ef-8a77-4aa2-94b8-de86372843ea\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-19T02:02:50.401375Z","caller":"traceutil/trace.go:171","msg":"trace[967703951] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5252a3ef-8a77-4aa2-94b8-de86372843ea; range_end:; response_count:1; response_revision:5724; }","duration":"303.526378ms","start":"2026-10-19T02:02:50.097791Z","end":"2026-10-19T02:02:50.401317Z","steps":["trace[967703951] 'agreement among raft nodes before linearized reading' (duration: 302.730979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:02:50.401419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:02:50.097763Z","time spent":"303.642652ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5252a3ef-8a77-4aa2-94b8-de86372843ea\" "} {"level":"info","ts":"2026-10-19T02:02:50.631351Z","caller":"traceutil/trace.go:171","msg":"trace[474068118] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"224.74452ms","start":"2026-10-19T02:02:50.406582Z","end":"2026-10-19T02:02:50.631326Z","steps":["trace[474068118] 'process raft request' (duration: 224.540805ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:02:50.632136Z","caller":"traceutil/trace.go:171","msg":"trace[1806699105] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"224.651097ms","start":"2026-10-19T02:02:50.40744Z","end":"2026-10-19T02:02:50.632091Z","steps":["trace[1806699105] 'process raft request' (duration: 223.843487ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:02:50.632989Z","caller":"traceutil/trace.go:171","msg":"trace[198812256] transaction","detail":"{read_only:false; response_revision:5728; number_of_response:1; }","duration":"220.640438ms","start":"2026-10-19T02:02:50.412307Z","end":"2026-10-19T02:02:50.632947Z","steps":["trace[198812256] 'process raft request' (duration: 220.407672ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:02:50.724838Z","caller":"traceutil/trace.go:171","msg":"trace[490446068] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"196.574449ms","start":"2026-10-19T02:02:50.528218Z","end":"2026-10-19T02:02:50.724793Z","steps":["trace[490446068] 'process raft request' (duration: 143.465059ms)","trace[490446068] 'compare' (duration: 52.795042ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:02:50.724843Z","caller":"traceutil/trace.go:171","msg":"trace[1815107453] linearizableReadLoop","detail":"{readStateIndex:5945; appliedIndex:5941; }","duration":"131.510211ms","start":"2026-10-19T02:02:50.593301Z","end":"2026-10-19T02:02:50.724811Z","steps":["trace[1815107453] 'read index received' (duration: 37.795579ms)","trace[1815107453] 'applied index is now lower than readState.Index' (duration: 93.711782ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:02:50.724982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.662385ms","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-10-19T02:02:50.725076Z","caller":"traceutil/trace.go:171","msg":"trace[1473917472] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5730; }","duration":"131.810859ms","start":"2026-10-19T02:02:50.593244Z","end":"2026-10-19T02:02:50.725055Z","steps":["trace[1473917472] 'agreement among raft nodes before linearized reading' (duration: 131.664365ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:02:54.477584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2942} {"level":"info","ts":"2026-10-19T02:02:54.558272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2942,"took":"79.063296ms","hash":3592736939,"current-db-size-bytes":56381440,"current-db-size":"56 MB","current-db-size-in-use-bytes":32075776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-19T02:02:54.558348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3592736939,"revision":2942,"compact-revision":-1} {"level":"info","ts":"2026-10-19T02:03:00.766296Z","caller":"traceutil/trace.go:171","msg":"trace[536734916] transaction","detail":"{read_only:false; response_revision:5865; number_of_response:1; }","duration":"154.054225ms","start":"2026-10-19T02:03:00.612203Z","end":"2026-10-19T02:03:00.766257Z","steps":["trace[536734916] 'process raft request' (duration: 153.807439ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:00.860912Z","caller":"traceutil/trace.go:171","msg":"trace[128155591] transaction","detail":"{read_only:false; response_revision:5866; number_of_response:1; }","duration":"101.23461ms","start":"2026-10-19T02:03:00.759634Z","end":"2026-10-19T02:03:00.860869Z","steps":["trace[128155591] 'process raft request' (duration: 51.77811ms)","trace[128155591] 'compare' (duration: 49.228794ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:03:00.861097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.800904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-19T02:03:00.861158Z","caller":"traceutil/trace.go:171","msg":"trace[1780375754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5867; }","duration":"154.936677ms","start":"2026-10-19T02:03:00.706208Z","end":"2026-10-19T02:03:00.861145Z","steps":["trace[1780375754] 'agreement among raft nodes before linearized reading' (duration: 154.680731ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:43.832798Z","caller":"traceutil/trace.go:171","msg":"trace[1828187496] transaction","detail":"{read_only:false; response_revision:6244; number_of_response:1; }","duration":"103.480648ms","start":"2026-10-19T02:03:43.729285Z","end":"2026-10-19T02:03:43.832765Z","steps":["trace[1828187496] 'process raft request' (duration: 103.299214ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:43.861234Z","caller":"traceutil/trace.go:171","msg":"trace[505921533] transaction","detail":"{read_only:false; response_revision:6245; number_of_response:1; }","duration":"128.809082ms","start":"2026-10-19T02:03:43.732389Z","end":"2026-10-19T02:03:43.861198Z","steps":["trace[505921533] 'process raft request' (duration: 109.030627ms)","trace[505921533] 'compare' (duration: 19.605452ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:03:59.055782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.201897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T02:03:59.056027Z","caller":"traceutil/trace.go:171","msg":"trace[2072638972] linearizableReadLoop","detail":"{readStateIndex:6582; appliedIndex:6581; }","duration":"187.181379ms","start":"2026-10-19T02:03:58.868723Z","end":"2026-10-19T02:03:59.055904Z","steps":["trace[2072638972] 'read index received' (duration: 19.552031ms)","trace[2072638972] 'applied index is now lower than readState.Index' (duration: 167.627308ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:03:59.056144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.431785ms","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-10-19T02:03:59.056182Z","caller":"traceutil/trace.go:171","msg":"trace[1623663876] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6349; }","duration":"187.523668ms","start":"2026-10-19T02:03:58.868644Z","end":"2026-10-19T02:03:59.056167Z","steps":["trace[1623663876] 'agreement among raft nodes before linearized reading' (duration: 187.443936ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:59.056352Z","caller":"traceutil/trace.go:171","msg":"trace[407897524] transaction","detail":"{read_only:false; response_revision:6349; number_of_response:1; }","duration":"197.783265ms","start":"2026-10-19T02:03:58.858517Z","end":"2026-10-19T02:03:59.0563Z","steps":["trace[407897524] 'process raft request' (duration: 29.919361ms)","trace[407897524] 'compare' (duration: 166.997752ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:03:59.214087Z","caller":"traceutil/trace.go:171","msg":"trace[2033689409] transaction","detail":"{read_only:false; response_revision:6350; number_of_response:1; }","duration":"124.856234ms","start":"2026-10-19T02:03:59.089175Z","end":"2026-10-19T02:03:59.214031Z","steps":["trace[2033689409] 'process raft request' (duration: 114.647228ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:59.222393Z","caller":"traceutil/trace.go:171","msg":"trace[1190237881] transaction","detail":"{read_only:false; response_revision:6351; number_of_response:1; }","duration":"131.425759ms","start":"2026-10-19T02:03:59.090917Z","end":"2026-10-19T02:03:59.222342Z","steps":["trace[1190237881] 'process raft request' (duration: 131.226415ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:07:54.486188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5808} {"level":"info","ts":"2026-10-19T02:07:54.581736Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5808,"took":"93.440883ms","hash":1949291849,"current-db-size-bytes":56381440,"current-db-size":"56 MB","current-db-size-in-use-bytes":26230784,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-19T02:07:54.581809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1949291849,"revision":5808,"compact-revision":2942} {"level":"info","ts":"2026-10-19T02:09:09.033565Z","caller":"traceutil/trace.go:171","msg":"trace[5120609] linearizableReadLoop","detail":"{readStateIndex:8933; appliedIndex:8932; }","duration":"152.691446ms","start":"2026-10-19T02:09:08.880849Z","end":"2026-10-19T02:09:09.033541Z","steps":["trace[5120609] 'read index received' (duration: 152.49503ms)","trace[5120609] 'applied index is now lower than readState.Index' (duration: 195.695µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:09:09.033666Z","caller":"traceutil/trace.go:171","msg":"trace[986561467] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"452.71031ms","start":"2026-10-19T02:09:08.580935Z","end":"2026-10-19T02:09:09.033646Z","steps":["trace[986561467] 'process raft request' (duration: 452.456644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:09:09.033799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:09:08.580896Z","time spent":"452.818243ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:09:09.033787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.905191ms","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-10-19T02:09:09.033857Z","caller":"traceutil/trace.go:171","msg":"trace[1920412200] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8622; }","duration":"153.039484ms","start":"2026-10-19T02:09:08.880803Z","end":"2026-10-19T02:09:09.033842Z","steps":["trace[1920412200] 'agreement among raft nodes before linearized reading' (duration: 152.87267ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:09:09.168592Z","caller":"traceutil/trace.go:171","msg":"trace[1101619404] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"129.780672ms","start":"2026-10-19T02:09:09.038783Z","end":"2026-10-19T02:09:09.168563Z","steps":["trace[1101619404] 'process raft request' (duration: 60.395701ms)","trace[1101619404] 'compare' (duration: 69.255247ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:09:09.172966Z","caller":"traceutil/trace.go:171","msg":"trace[1252565236] linearizableReadLoop","detail":"{readStateIndex:8935; appliedIndex:8933; }","duration":"112.020888ms","start":"2026-10-19T02:09:09.060922Z","end":"2026-10-19T02:09:09.172943Z","steps":["trace[1252565236] 'read index received' (duration: 38.271827ms)","trace[1252565236] 'applied index is now lower than readState.Index' (duration: 73.748141ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:09:09.1731Z","caller":"traceutil/trace.go:171","msg":"trace[1038198920] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"133.975698ms","start":"2026-10-19T02:09:09.039086Z","end":"2026-10-19T02:09:09.173061Z","steps":["trace[1038198920] 'process raft request' (duration: 133.768333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:09:09.173233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.287165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-19T02:09:09.173314Z","caller":"traceutil/trace.go:171","msg":"trace[1192159960] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8624; }","duration":"112.411428ms","start":"2026-10-19T02:09:09.060888Z","end":"2026-10-19T02:09:09.1733Z","steps":["trace[1192159960] 'agreement among raft nodes before linearized reading' (duration: 112.196753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:09:09.174091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.097482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-19T02:09:09.174195Z","caller":"traceutil/trace.go:171","msg":"trace[1203116966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8625; }","duration":"105.247056ms","start":"2026-10-19T02:09:09.068925Z","end":"2026-10-19T02:09:09.174172Z","steps":["trace[1203116966] 'agreement among raft nodes before linearized reading' (duration: 104.971309ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:09:20.233943Z","caller":"traceutil/trace.go:171","msg":"trace[1539577491] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"197.805916ms","start":"2026-10-19T02:09:20.036111Z","end":"2026-10-19T02:09:20.233917Z","steps":["trace[1539577491] 'process raft request' (duration: 197.459737ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:09:21.392631Z","caller":"traceutil/trace.go:171","msg":"trace[1115715537] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"221.106171ms","start":"2026-10-19T02:09:21.171496Z","end":"2026-10-19T02:09:21.392602Z","steps":["trace[1115715537] 'process raft request' (duration: 220.912856ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:09:21.399034Z","caller":"traceutil/trace.go:171","msg":"trace[1492394819] linearizableReadLoop","detail":"{readStateIndex:9002; appliedIndex:9000; }","duration":"162.569538ms","start":"2026-10-19T02:09:21.236442Z","end":"2026-10-19T02:09:21.399012Z","steps":["trace[1492394819] 'read index received' (duration: 156.139284ms)","trace[1492394819] 'applied index is now lower than readState.Index' (duration: 6.429064ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:09:21.399169Z","caller":"traceutil/trace.go:171","msg":"trace[1205478003] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"176.201385ms","start":"2026-10-19T02:09:21.222924Z","end":"2026-10-19T02:09:21.399125Z","steps":["trace[1205478003] 'process raft request' (duration: 175.918238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:09:21.399355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.634592ms","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-10-19T02:09:21.399408Z","caller":"traceutil/trace.go:171","msg":"trace[781151247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8689; }","duration":"157.745925ms","start":"2026-10-19T02:09:21.241651Z","end":"2026-10-19T02:09:21.399397Z","steps":["trace[781151247] 'agreement among raft nodes before linearized reading' (duration: 157.53688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:09:21.39949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.067203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-19T02:09:21.399552Z","caller":"traceutil/trace.go:171","msg":"trace[496963741] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"174.465771ms","start":"2026-10-19T02:09:21.224674Z","end":"2026-10-19T02:09:21.399139Z","steps":["trace[496963741] 'process raft request' (duration: 174.297147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:09:21.39955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.354709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-19T02:09:21.399577Z","caller":"traceutil/trace.go:171","msg":"trace[1129366439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8689; }","duration":"147.195156ms","start":"2026-10-19T02:09:21.252358Z","end":"2026-10-19T02:09:21.399553Z","steps":["trace[1129366439] 'agreement among raft nodes before linearized reading' (duration: 146.998481ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:09:21.399627Z","caller":"traceutil/trace.go:171","msg":"trace[1113051425] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8689; }","duration":"145.502792ms","start":"2026-10-19T02:09:21.254105Z","end":"2026-10-19T02:09:21.399608Z","steps":["trace[1113051425] 'agreement among raft nodes before linearized reading' (duration: 145.243156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:09:21.399588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.119932ms","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-10-19T02:09:21.399716Z","caller":"traceutil/trace.go:171","msg":"trace[885638048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8689; }","duration":"163.336148ms","start":"2026-10-19T02:09:21.236355Z","end":"2026-10-19T02:09:21.399691Z","steps":["trace[885638048] 'agreement among raft nodes before linearized reading' (duration: 162.951268ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:09:51.383756Z","caller":"traceutil/trace.go:171","msg":"trace[952696594] transaction","detail":"{read_only:false; response_revision:8900; number_of_response:1; }","duration":"150.143988ms","start":"2026-10-19T02:09:51.233579Z","end":"2026-10-19T02:09:51.383723Z","steps":["trace[952696594] 'process raft request' (duration: 149.984704ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:09:57.314733Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002425.wal"} {"level":"info","ts":"2026-10-19T02:09:59.212155Z","caller":"traceutil/trace.go:171","msg":"trace[273054966] linearizableReadLoop","detail":"{readStateIndex:9265; appliedIndex:9264; }","duration":"144.878273ms","start":"2026-10-19T02:09:59.067252Z","end":"2026-10-19T02:09:59.21213Z","steps":["trace[273054966] 'read index received' (duration: 144.663218ms)","trace[273054966] 'applied index is now lower than readState.Index' (duration: 213.905µs)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:09:59.212278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.011897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:09:59.212254Z","caller":"traceutil/trace.go:171","msg":"trace[1895681885] transaction","detail":"{read_only:false; response_revision:8941; number_of_response:1; }","duration":"281.779998ms","start":"2026-10-19T02:09:58.930437Z","end":"2026-10-19T02:09:59.212217Z","steps":["trace[1895681885] 'process raft request' (duration: 281.46882ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:09:59.212336Z","caller":"traceutil/trace.go:171","msg":"trace[930161433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8941; }","duration":"145.13437ms","start":"2026-10-19T02:09:59.067192Z","end":"2026-10-19T02:09:59.212326Z","steps":["trace[930161433] 'agreement among raft nodes before linearized reading' (duration: 145.032968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:09:59.775412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.243059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T02:09:59.775499Z","caller":"traceutil/trace.go:171","msg":"trace[1649216554] linearizableReadLoop","detail":"{readStateIndex:9269; appliedIndex:9268; }","duration":"138.898163ms","start":"2026-10-19T02:09:59.636588Z","end":"2026-10-19T02:09:59.775486Z","steps":["trace[1649216554] 'read index received' (duration: 19.424268ms)","trace[1649216554] 'applied index is now lower than readState.Index' (duration: 119.473125ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:09:59.775545Z","caller":"traceutil/trace.go:171","msg":"trace[50363249] transaction","detail":"{read_only:false; response_revision:8945; number_of_response:1; }","duration":"147.175802ms","start":"2026-10-19T02:09:59.628334Z","end":"2026-10-19T02:09:59.77551Z","steps":["trace[50363249] 'process raft request' (duration: 27.759058ms)","trace[50363249] 'compare' (duration: 119.125926ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:09:59.775642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.241179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-19T02:09:59.775662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.083838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-19T02:09:59.775725Z","caller":"traceutil/trace.go:171","msg":"trace[1880897498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8945; }","duration":"139.219592ms","start":"2026-10-19T02:09:59.636489Z","end":"2026-10-19T02:09:59.775709Z","steps":["trace[1880897498] 'agreement among raft nodes before linearized reading' (duration: 139.050467ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:09:59.775685Z","caller":"traceutil/trace.go:171","msg":"trace[1099142832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8945; }","duration":"117.316671ms","start":"2026-10-19T02:09:59.658356Z","end":"2026-10-19T02:09:59.775673Z","steps":["trace[1099142832] 'agreement among raft nodes before linearized reading' (duration: 117.202408ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:00.047421Z","caller":"traceutil/trace.go:171","msg":"trace[2088026100] linearizableReadLoop","detail":"{readStateIndex:9273; appliedIndex:9269; }","duration":"176.733426ms","start":"2026-10-19T02:09:59.870668Z","end":"2026-10-19T02:10:00.047401Z","steps":["trace[2088026100] 'read index received' (duration: 92.680341ms)","trace[2088026100] 'applied index is now lower than readState.Index' (duration: 84.052225ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:10:00.047599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.922271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-65ccdd9699-blb6v.18dfcc3d2eda9386\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-10-19T02:10:00.047624Z","caller":"traceutil/trace.go:171","msg":"trace[1993048514] range","detail":"{range_begin:/registry/events/openstack/barbican-api-65ccdd9699-blb6v.18dfcc3d2eda9386; range_end:; response_count:1; response_revision:8949; }","duration":"176.997663ms","start":"2026-10-19T02:09:59.870616Z","end":"2026-10-19T02:10:00.047613Z","steps":["trace[1993048514] 'agreement among raft nodes before linearized reading' (duration: 176.85846ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:00.047668Z","caller":"traceutil/trace.go:171","msg":"trace[114712233] transaction","detail":"{read_only:false; response_revision:8948; number_of_response:1; }","duration":"266.172236ms","start":"2026-10-19T02:09:59.781476Z","end":"2026-10-19T02:10:00.047648Z","steps":["trace[114712233] 'process raft request' (duration: 265.871518ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:00.047706Z","caller":"traceutil/trace.go:171","msg":"trace[1065343361] transaction","detail":"{read_only:false; response_revision:8947; number_of_response:1; }","duration":"266.254659ms","start":"2026-10-19T02:09:59.781418Z","end":"2026-10-19T02:10:00.047672Z","steps":["trace[1065343361] 'process raft request' (duration: 265.863689ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:00.047745Z","caller":"traceutil/trace.go:171","msg":"trace[317215449] transaction","detail":"{read_only:false; response_revision:8946; number_of_response:1; }","duration":"267.253174ms","start":"2026-10-19T02:09:59.780463Z","end":"2026-10-19T02:10:00.047716Z","steps":["trace[317215449] 'process raft request' (duration: 182.662235ms)","trace[317215449] 'compare' (duration: 83.822939ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:10:00.047787Z","caller":"traceutil/trace.go:171","msg":"trace[1386523697] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"265.92222ms","start":"2026-10-19T02:09:59.781829Z","end":"2026-10-19T02:10:00.047751Z","steps":["trace[1386523697] 'process raft request' (duration: 265.54597ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.257787Z","caller":"traceutil/trace.go:171","msg":"trace[2104581324] linearizableReadLoop","detail":"{readStateIndex:9323; appliedIndex:9322; }","duration":"123.496847ms","start":"2026-10-19T02:10:05.134267Z","end":"2026-10-19T02:10:05.257764Z","steps":["trace[2104581324] 'read index received' (duration: 91.222465ms)","trace[2104581324] 'applied index is now lower than readState.Index' (duration: 32.273032ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:10:05.257814Z","caller":"traceutil/trace.go:171","msg":"trace[548334741] transaction","detail":"{read_only:false; response_revision:8997; number_of_response:1; }","duration":"129.115568ms","start":"2026-10-19T02:10:05.128677Z","end":"2026-10-19T02:10:05.257793Z","steps":["trace[548334741] 'process raft request' (duration: 96.799114ms)","trace[548334741] 'compare' (duration: 32.057677ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:10:05.257969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.051757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:499"} {"level":"info","ts":"2026-10-19T02:10:05.257995Z","caller":"traceutil/trace.go:171","msg":"trace[933080783] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8997; }","duration":"129.138519ms","start":"2026-10-19T02:10:05.128849Z","end":"2026-10-19T02:10:05.257988Z","steps":["trace[933080783] 'agreement among raft nodes before linearized reading' (duration: 129.011966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.258078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.067552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-10-19T02:10:05.258179Z","caller":"traceutil/trace.go:171","msg":"trace[491542399] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:8997; }","duration":"128.206186ms","start":"2026-10-19T02:10:05.129941Z","end":"2026-10-19T02:10:05.258147Z","steps":["trace[491542399] 'agreement among raft nodes before linearized reading' (duration: 127.906498ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.32199Z","caller":"traceutil/trace.go:171","msg":"trace[1376936941] transaction","detail":"{read_only:false; response_revision:8998; number_of_response:1; }","duration":"180.003129ms","start":"2026-10-19T02:10:05.141949Z","end":"2026-10-19T02:10:05.321952Z","steps":["trace[1376936941] 'process raft request' (duration: 179.806924ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.322085Z","caller":"traceutil/trace.go:171","msg":"trace[2090116042] transaction","detail":"{read_only:false; response_revision:8999; number_of_response:1; }","duration":"179.342631ms","start":"2026-10-19T02:10:05.142717Z","end":"2026-10-19T02:10:05.322059Z","steps":["trace[2090116042] 'process raft request' (duration: 179.184257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.322412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.384533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/rook-ceph-cluster\" ","response":"range_response_count:1 size:956"} {"level":"warn","ts":"2026-10-19T02:10:05.322459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.605875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-hc4kq\" ","response":"range_response_count:1 size:4781"} {"level":"info","ts":"2026-10-19T02:10:05.322489Z","caller":"traceutil/trace.go:171","msg":"trace[416178814] range","detail":"{range_begin:/registry/ingress/openstack/rook-ceph-cluster; range_end:; response_count:1; response_revision:9000; }","duration":"185.590699ms","start":"2026-10-19T02:10:05.136881Z","end":"2026-10-19T02:10:05.322472Z","steps":["trace[416178814] 'agreement among raft nodes before linearized reading' (duration: 185.26219ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.322522Z","caller":"traceutil/trace.go:171","msg":"trace[659766690] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-hc4kq; range_end:; response_count:1; response_revision:9000; }","duration":"186.728338ms","start":"2026-10-19T02:10:05.135775Z","end":"2026-10-19T02:10:05.322504Z","steps":["trace[659766690] 'agreement among raft nodes before linearized reading' (duration: 186.569004ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.322505Z","caller":"traceutil/trace.go:171","msg":"trace[1158167454] transaction","detail":"{read_only:false; response_revision:9000; number_of_response:1; }","duration":"166.5856ms","start":"2026-10-19T02:10:05.155899Z","end":"2026-10-19T02:10:05.322485Z","steps":["trace[1158167454] 'process raft request' (duration: 166.049887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.322613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.837511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3138"} {"level":"info","ts":"2026-10-19T02:10:05.322708Z","caller":"traceutil/trace.go:171","msg":"trace[1686584799] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9000; }","duration":"186.956394ms","start":"2026-10-19T02:10:05.135738Z","end":"2026-10-19T02:10:05.322694Z","steps":["trace[1686584799] 'agreement among raft nodes before linearized reading' (duration: 186.657236ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.51223Z","caller":"traceutil/trace.go:171","msg":"trace[1726435064] linearizableReadLoop","detail":"{readStateIndex:9328; appliedIndex:9327; }","duration":"174.343106ms","start":"2026-10-19T02:10:05.337856Z","end":"2026-10-19T02:10:05.512199Z","steps":["trace[1726435064] 'read index received' (duration: 111.130796ms)","trace[1726435064] 'applied index is now lower than readState.Index' (duration: 63.21124ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:10:05.512375Z","caller":"traceutil/trace.go:171","msg":"trace[1685313421] transaction","detail":"{read_only:false; response_revision:9002; number_of_response:1; }","duration":"184.950433ms","start":"2026-10-19T02:10:05.327348Z","end":"2026-10-19T02:10:05.512299Z","steps":["trace[1685313421] 'process raft request' (duration: 121.63046ms)","trace[1685313421] 'compare' (duration: 63.108147ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:10:05.512492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.2596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3138"} {"level":"info","ts":"2026-10-19T02:10:05.512572Z","caller":"traceutil/trace.go:171","msg":"trace[760802094] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9002; }","duration":"185.386763ms","start":"2026-10-19T02:10:05.327166Z","end":"2026-10-19T02:10:05.512552Z","steps":["trace[760802094] 'agreement among raft nodes before linearized reading' (duration: 185.130537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.512653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.361423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-rgw-ceph-certs\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:10:05.512791Z","caller":"traceutil/trace.go:171","msg":"trace[1458828703] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-rgw-ceph-certs; range_end:; response_count:0; response_revision:9002; }","duration":"183.520437ms","start":"2026-10-19T02:10:05.329224Z","end":"2026-10-19T02:10:05.512745Z","steps":["trace[1458828703] 'agreement among raft nodes before linearized reading' (duration: 183.058385ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.536083Z","caller":"traceutil/trace.go:171","msg":"trace[738237056] transaction","detail":"{read_only:false; response_revision:9003; number_of_response:1; }","duration":"190.03502ms","start":"2026-10-19T02:10:05.346027Z","end":"2026-10-19T02:10:05.536062Z","steps":["trace[738237056] 'process raft request' (duration: 189.891256ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.566789Z","caller":"traceutil/trace.go:171","msg":"trace[1791116715] transaction","detail":"{read_only:false; response_revision:9004; number_of_response:1; }","duration":"115.093975ms","start":"2026-10-19T02:10:05.451652Z","end":"2026-10-19T02:10:05.566746Z","steps":["trace[1791116715] 'process raft request' (duration: 114.800858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.567301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.923604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-mon\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-10-19T02:10:05.567417Z","caller":"traceutil/trace.go:171","msg":"trace[1878098009] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-mon; range_end:; response_count:1; response_revision:9005; }","duration":"226.069027ms","start":"2026-10-19T02:10:05.341329Z","end":"2026-10-19T02:10:05.567398Z","steps":["trace[1878098009] 'agreement among raft nodes before linearized reading' (duration: 225.692837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.567463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.109778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-10-19T02:10:05.56751Z","caller":"traceutil/trace.go:171","msg":"trace[1981731553] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9005; }","duration":"226.378374ms","start":"2026-10-19T02:10:05.341118Z","end":"2026-10-19T02:10:05.567497Z","steps":["trace[1981731553] 'agreement among raft nodes before linearized reading' (duration: 226.040536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.567818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.199406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-19T02:10:05.567891Z","caller":"traceutil/trace.go:171","msg":"trace[427716781] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:9005; }","duration":"225.311959ms","start":"2026-10-19T02:10:05.342566Z","end":"2026-10-19T02:10:05.567878Z","steps":["trace[427716781] 'agreement among raft nodes before linearized reading' (duration: 224.727394ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.568364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.308712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-19T02:10:05.568435Z","caller":"traceutil/trace.go:171","msg":"trace[405710872] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:9005; }","duration":"216.419345ms","start":"2026-10-19T02:10:05.351999Z","end":"2026-10-19T02:10:05.568418Z","steps":["trace[405710872] 'agreement among raft nodes before linearized reading' (duration: 215.286136ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.887043Z","caller":"traceutil/trace.go:171","msg":"trace[1944931891] transaction","detail":"{read_only:false; response_revision:9006; number_of_response:1; }","duration":"317.304712ms","start":"2026-10-19T02:10:05.569714Z","end":"2026-10-19T02:10:05.887019Z","steps":["trace[1944931891] 'process raft request' (duration: 237.71709ms)","trace[1944931891] 'compare' (duration: 79.405947ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:10:05.887175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.56969Z","time spent":"317.414085ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4754,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:10:05.954786Z","caller":"traceutil/trace.go:171","msg":"trace[461058109] transaction","detail":"{read_only:false; response_revision:9008; number_of_response:1; }","duration":"370.141772ms","start":"2026-10-19T02:10:05.584618Z","end":"2026-10-19T02:10:05.95476Z","steps":["trace[461058109] 'process raft request' (duration: 369.923396ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.954887Z","caller":"traceutil/trace.go:171","msg":"trace[1760121075] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9008; }","duration":"367.574587ms","start":"2026-10-19T02:10:05.587239Z","end":"2026-10-19T02:10:05.954813Z","steps":["trace[1760121075] 'process raft request' (duration: 367.409083ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.954914Z","caller":"traceutil/trace.go:171","msg":"trace[302933086] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"384.276086ms","start":"2026-10-19T02:10:05.570601Z","end":"2026-10-19T02:10:05.954877Z","steps":["trace[302933086] 'process raft request' (duration: 383.782464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.95497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.584602Z","time spent":"370.273544ms","remote":"127.0.0.1:41928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2801,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:10:05.955042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.570575Z","time spent":"384.41184ms","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:10:05.955124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.587203Z","time spent":"367.785572ms","remote":"127.0.0.1:41928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":77,"response count":0,"response size":2851,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:10:05.95515Z","caller":"traceutil/trace.go:171","msg":"trace[1915954511] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"159.614776ms","start":"2026-10-19T02:10:05.795506Z","end":"2026-10-19T02:10:05.955121Z","steps":["trace[1915954511] 'process raft request' (duration: 159.478842ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.955163Z","caller":"traceutil/trace.go:171","msg":"trace[897633415] linearizableReadLoop","detail":"{readStateIndex:9333; appliedIndex:9331; }","duration":"383.346294ms","start":"2026-10-19T02:10:05.571769Z","end":"2026-10-19T02:10:05.955115Z","steps":["trace[897633415] 'read index received' (duration: 235.634257ms)","trace[897633415] 'applied index is now lower than readState.Index' (duration: 147.710717ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:10:05.955199Z","caller":"traceutil/trace.go:171","msg":"trace[1661078401] transaction","detail":"{read_only:false; response_revision:9009; number_of_response:1; }","duration":"302.9279ms","start":"2026-10-19T02:10:05.652213Z","end":"2026-10-19T02:10:05.955141Z","steps":["trace[1661078401] 'process raft request' (duration: 302.473509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.95542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.652189Z","time spent":"303.106525ms","remote":"127.0.0.1:49694","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-10-19T02:10:05.95542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.642681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-mon-endpoints\" ","response":"range_response_count:1 size:389"} {"level":"warn","ts":"2026-10-19T02:10:05.955451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.961987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-scvjj\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-19T02:10:05.955479Z","caller":"traceutil/trace.go:171","msg":"trace[1902919861] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-mon-endpoints; range_end:; response_count:1; response_revision:9010; }","duration":"383.703832ms","start":"2026-10-19T02:10:05.57176Z","end":"2026-10-19T02:10:05.955464Z","steps":["trace[1902919861] 'agreement among raft nodes before linearized reading' (duration: 383.478467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.955491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.254282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-10-19T02:10:05.955522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.571713Z","time spent":"383.800764ms","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":413,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-mon-endpoints\" "} {"level":"warn","ts":"2026-10-19T02:10:05.95552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.123766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-10-19T02:10:05.95553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.390881ms","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-10-19T02:10:05.955551Z","caller":"traceutil/trace.go:171","msg":"trace[452285708] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9010; }","duration":"383.355613ms","start":"2026-10-19T02:10:05.572178Z","end":"2026-10-19T02:10:05.955533Z","steps":["trace[452285708] 'agreement among raft nodes before linearized reading' (duration: 383.155519ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.955614Z","caller":"traceutil/trace.go:171","msg":"trace[2066343432] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9010; }","duration":"171.507944ms","start":"2026-10-19T02:10:05.784084Z","end":"2026-10-19T02:10:05.955592Z","steps":["trace[2066343432] 'agreement among raft nodes before linearized reading' (duration: 171.353441ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.955676Z","caller":"traceutil/trace.go:171","msg":"trace[293887062] range","detail":"{range_begin:/registry/pods/openstack/keepalived-scvjj; range_end:; response_count:1; response_revision:9010; }","duration":"143.025808ms","start":"2026-10-19T02:10:05.812453Z","end":"2026-10-19T02:10:05.955478Z","steps":["trace[293887062] 'agreement among raft nodes before linearized reading' (duration: 142.883214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.955617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.572163Z","time spent":"383.439976ms","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":555,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-10-19T02:10:05.955499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.393844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-19T02:10:05.955819Z","caller":"traceutil/trace.go:171","msg":"trace[479686434] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:9010; }","duration":"340.771092ms","start":"2026-10-19T02:10:05.61503Z","end":"2026-10-19T02:10:05.955801Z","steps":["trace[479686434] 'agreement among raft nodes before linearized reading' (duration: 340.318992ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:05.955572Z","caller":"traceutil/trace.go:171","msg":"trace[2077221143] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:9010; }","duration":"379.218709ms","start":"2026-10-19T02:10:05.57634Z","end":"2026-10-19T02:10:05.955559Z","steps":["trace[2077221143] 'agreement among raft nodes before linearized reading' (duration: 379.082066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.955877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.615003Z","time spent":"340.858695ms","remote":"127.0.0.1:49520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1845,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-10-19T02:10:05.955892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.576301Z","time spent":"379.577778ms","remote":"127.0.0.1:49804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-10-19T02:10:05.95611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.109476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112103"} {"level":"info","ts":"2026-10-19T02:10:05.956196Z","caller":"traceutil/trace.go:171","msg":"trace[1879550014] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:9010; }","duration":"379.208189ms","start":"2026-10-19T02:10:05.576965Z","end":"2026-10-19T02:10:05.956173Z","steps":["trace[1879550014] 'agreement among raft nodes before linearized reading' (duration: 378.416519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:05.956237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:05.576945Z","time spent":"379.283601ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":16,"response size":112127,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-10-19T02:10:06.203345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.627141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:10:06.203443Z","caller":"traceutil/trace.go:171","msg":"trace[660992012] linearizableReadLoop","detail":"{readStateIndex:9338; appliedIndex:9337; }","duration":"241.038614ms","start":"2026-10-19T02:10:05.962389Z","end":"2026-10-19T02:10:06.203428Z","steps":["trace[660992012] 'read index received' (duration: 80.097394ms)","trace[660992012] 'applied index is now lower than readState.Index' (duration: 160.939999ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:10:06.203514Z","caller":"traceutil/trace.go:171","msg":"trace[214934523] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9011; }","duration":"241.578357ms","start":"2026-10-19T02:10:05.961925Z","end":"2026-10-19T02:10:06.203504Z","steps":["trace[214934523] 'process raft request' (duration: 80.624888ms)","trace[214934523] 'compare' (duration: 160.529629ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:10:06.203969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.79176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-19T02:10:06.204031Z","caller":"traceutil/trace.go:171","msg":"trace[589262484] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9011; }","duration":"126.918613ms","start":"2026-10-19T02:10:06.077095Z","end":"2026-10-19T02:10:06.204014Z","steps":["trace[589262484] 'agreement among raft nodes before linearized reading' (duration: 126.693338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.204053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.098719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:10:06.204134Z","caller":"traceutil/trace.go:171","msg":"trace[1676030128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9011; }","duration":"137.230352ms","start":"2026-10-19T02:10:06.066886Z","end":"2026-10-19T02:10:06.204116Z","steps":["trace[1676030128] 'agreement among raft nodes before linearized reading' (duration: 137.113969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.204238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.279452ms","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-10-19T02:10:06.204299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.367639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-19T02:10:06.20438Z","caller":"traceutil/trace.go:171","msg":"trace[279750537] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:9011; }","duration":"227.492142ms","start":"2026-10-19T02:10:05.976874Z","end":"2026-10-19T02:10:06.204366Z","steps":["trace[279750537] 'agreement among raft nodes before linearized reading' (duration: 227.252926ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.204395Z","caller":"traceutil/trace.go:171","msg":"trace[748859831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9011; }","duration":"125.445467ms","start":"2026-10-19T02:10:06.078901Z","end":"2026-10-19T02:10:06.204346Z","steps":["trace[748859831] 'agreement among raft nodes before linearized reading' (duration: 125.101818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.204337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.302743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-19T02:10:06.204543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.088186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-10-19T02:10:06.204598Z","caller":"traceutil/trace.go:171","msg":"trace[320364567] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:9011; }","duration":"212.172498ms","start":"2026-10-19T02:10:05.992407Z","end":"2026-10-19T02:10:06.20458Z","steps":["trace[320364567] 'agreement among raft nodes before linearized reading' (duration: 211.817829ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.204543Z","caller":"traceutil/trace.go:171","msg":"trace[1901044618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9011; }","duration":"125.539929ms","start":"2026-10-19T02:10:06.078974Z","end":"2026-10-19T02:10:06.204514Z","steps":["trace[1901044618] 'agreement among raft nodes before linearized reading' (duration: 125.013106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.204659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.500735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:229777"} {"level":"warn","ts":"2026-10-19T02:10:06.204737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.299478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-rgw-ceph-certs\" ","response":"range_response_count:1 size:4366"} {"level":"info","ts":"2026-10-19T02:10:06.204749Z","caller":"traceutil/trace.go:171","msg":"trace[304928275] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9011; }","duration":"239.613047ms","start":"2026-10-19T02:10:05.965123Z","end":"2026-10-19T02:10:06.204736Z","steps":["trace[304928275] 'agreement among raft nodes before linearized reading' (duration: 238.563041ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.204769Z","caller":"traceutil/trace.go:171","msg":"trace[1688133492] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-rgw-ceph-certs; range_end:; response_count:1; response_revision:9011; }","duration":"231.347439ms","start":"2026-10-19T02:10:05.97341Z","end":"2026-10-19T02:10:06.204758Z","steps":["trace[1688133492] 'agreement among raft nodes before linearized reading' (duration: 230.696864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.204861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.625828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-hc4kq\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-10-19T02:10:06.204902Z","caller":"traceutil/trace.go:171","msg":"trace[1683316317] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-hc4kq; range_end:; response_count:1; response_revision:9011; }","duration":"239.686129ms","start":"2026-10-19T02:10:05.965203Z","end":"2026-10-19T02:10:06.204889Z","steps":["trace[1683316317] 'agreement among raft nodes before linearized reading' (duration: 238.990132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.204992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.852396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-10-19T02:10:06.205064Z","caller":"traceutil/trace.go:171","msg":"trace[1800721192] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:9011; }","duration":"160.963849ms","start":"2026-10-19T02:10:06.044087Z","end":"2026-10-19T02:10:06.205051Z","steps":["trace[1800721192] 'agreement among raft nodes before linearized reading' (duration: 160.139309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.205066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.951668ms","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-10-19T02:10:06.205094Z","caller":"traceutil/trace.go:171","msg":"trace[1842109632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9011; }","duration":"126.00032ms","start":"2026-10-19T02:10:06.079085Z","end":"2026-10-19T02:10:06.205086Z","steps":["trace[1842109632] 'agreement among raft nodes before linearized reading' (duration: 124.994645ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.205219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.800477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-hc4kq\" ","response":"range_response_count:1 size:4769"} {"level":"info","ts":"2026-10-19T02:10:06.20534Z","caller":"traceutil/trace.go:171","msg":"trace[1641572391] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-hc4kq; range_end:; response_count:1; response_revision:9011; }","duration":"242.955091ms","start":"2026-10-19T02:10:05.962337Z","end":"2026-10-19T02:10:06.205293Z","steps":["trace[1641572391] 'agreement among raft nodes before linearized reading' (duration: 241.70086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.205876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.344643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4798"} {"level":"info","ts":"2026-10-19T02:10:06.20596Z","caller":"traceutil/trace.go:171","msg":"trace[727977738] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:9011; }","duration":"224.471436ms","start":"2026-10-19T02:10:05.981463Z","end":"2026-10-19T02:10:06.205935Z","steps":["trace[727977738] 'agreement among raft nodes before linearized reading' (duration: 224.196229ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.361364Z","caller":"traceutil/trace.go:171","msg":"trace[2010885736] transaction","detail":"{read_only:false; response_revision:9014; number_of_response:1; }","duration":"151.318636ms","start":"2026-10-19T02:10:06.21002Z","end":"2026-10-19T02:10:06.361339Z","steps":["trace[2010885736] 'process raft request' (duration: 151.214154ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.361709Z","caller":"traceutil/trace.go:171","msg":"trace[964027018] transaction","detail":"{read_only:false; response_revision:9015; number_of_response:1; }","duration":"151.320917ms","start":"2026-10-19T02:10:06.210357Z","end":"2026-10-19T02:10:06.361678Z","steps":["trace[964027018] 'process raft request' (duration: 150.927138ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.361722Z","caller":"traceutil/trace.go:171","msg":"trace[855521250] transaction","detail":"{read_only:false; response_revision:9013; number_of_response:1; }","duration":"151.716166ms","start":"2026-10-19T02:10:06.209912Z","end":"2026-10-19T02:10:06.361629Z","steps":["trace[855521250] 'process raft request' (duration: 109.460263ms)","trace[855521250] 'compare' (duration: 41.72702ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:10:06.362409Z","caller":"traceutil/trace.go:171","msg":"trace[1904866766] transaction","detail":"{read_only:false; response_revision:9016; number_of_response:1; }","duration":"151.000768ms","start":"2026-10-19T02:10:06.211389Z","end":"2026-10-19T02:10:06.36239Z","steps":["trace[1904866766] 'process raft request' (duration: 150.186677ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.362591Z","caller":"traceutil/trace.go:171","msg":"trace[1162956859] transaction","detail":"{read_only:false; response_revision:9017; number_of_response:1; }","duration":"151.135852ms","start":"2026-10-19T02:10:06.211434Z","end":"2026-10-19T02:10:06.36257Z","steps":["trace[1162956859] 'process raft request' (duration: 150.826344ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.362865Z","caller":"traceutil/trace.go:171","msg":"trace[1838610941] linearizableReadLoop","detail":"{readStateIndex:9341; appliedIndex:9338; }","duration":"152.637019ms","start":"2026-10-19T02:10:06.210197Z","end":"2026-10-19T02:10:06.362834Z","steps":["trace[1838610941] 'read index received' (duration: 51.383802ms)","trace[1838610941] 'applied index is now lower than readState.Index' (duration: 101.252387ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:10:06.362905Z","caller":"traceutil/trace.go:171","msg":"trace[709530660] transaction","detail":"{read_only:false; response_revision:9018; number_of_response:1; }","duration":"148.6618ms","start":"2026-10-19T02:10:06.214235Z","end":"2026-10-19T02:10:06.362897Z","steps":["trace[709530660] 'process raft request' (duration: 148.156237ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.362867Z","caller":"traceutil/trace.go:171","msg":"trace[127244610] transaction","detail":"{read_only:false; response_revision:9019; number_of_response:1; }","duration":"147.638074ms","start":"2026-10-19T02:10:06.215215Z","end":"2026-10-19T02:10:06.362853Z","steps":["trace[127244610] 'process raft request' (duration: 147.230974ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.36308Z","caller":"traceutil/trace.go:171","msg":"trace[1620086360] transaction","detail":"{read_only:false; response_revision:9020; number_of_response:1; }","duration":"142.784752ms","start":"2026-10-19T02:10:06.220248Z","end":"2026-10-19T02:10:06.363033Z","steps":["trace[1620086360] 'process raft request' (duration: 142.260838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.363456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.229954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-19T02:10:06.363578Z","caller":"traceutil/trace.go:171","msg":"trace[848888379] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:9020; }","duration":"153.345268ms","start":"2026-10-19T02:10:06.210161Z","end":"2026-10-19T02:10:06.363507Z","steps":["trace[848888379] 'agreement among raft nodes before linearized reading' (duration: 152.923837ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:06.368993Z","caller":"traceutil/trace.go:171","msg":"trace[266724139] transaction","detail":"{read_only:false; response_revision:9021; number_of_response:1; }","duration":"101.915414ms","start":"2026-10-19T02:10:06.26703Z","end":"2026-10-19T02:10:06.368945Z","steps":["trace[266724139] 'process raft request' (duration: 101.7391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:06.369273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.054696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-10-19T02:10:06.369376Z","caller":"traceutil/trace.go:171","msg":"trace[1717580301] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:9021; }","duration":"156.18966ms","start":"2026-10-19T02:10:06.213164Z","end":"2026-10-19T02:10:06.369354Z","steps":["trace[1717580301] 'agreement among raft nodes before linearized reading' (duration: 155.995145ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:08.249097Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2af340d70e332c3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T02:11:08.255513Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-19T02:11:08.25561Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-19T02:11:31.068297Z","caller":"traceutil/trace.go:171","msg":"trace[721415368] linearizableReadLoop","detail":"{readStateIndex:10165; appliedIndex:10164; }","duration":"162.414696ms","start":"2026-10-19T02:11:30.905852Z","end":"2026-10-19T02:11:31.068267Z","steps":["trace[721415368] 'read index received' (duration: 162.009255ms)","trace[721415368] 'applied index is now lower than readState.Index' (duration: 403.991µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:11:31.068361Z","caller":"traceutil/trace.go:171","msg":"trace[1540366076] transaction","detail":"{read_only:false; response_revision:9816; number_of_response:1; }","duration":"187.512467ms","start":"2026-10-19T02:11:30.880825Z","end":"2026-10-19T02:11:31.068338Z","steps":["trace[1540366076] 'process raft request' (duration: 187.096216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:31.068499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.643391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-19T02:11:31.068528Z","caller":"traceutil/trace.go:171","msg":"trace[503024118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9816; }","duration":"162.741644ms","start":"2026-10-19T02:11:30.905778Z","end":"2026-10-19T02:11:31.068519Z","steps":["trace[503024118] 'agreement among raft nodes before linearized reading' (duration: 162.629251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:31.068699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.241553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-19T02:11:31.068735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.636435ms","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-10-19T02:11:31.068773Z","caller":"traceutil/trace.go:171","msg":"trace[1511250833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9816; }","duration":"141.340335ms","start":"2026-10-19T02:11:30.927416Z","end":"2026-10-19T02:11:31.068756Z","steps":["trace[1511250833] 'agreement among raft nodes before linearized reading' (duration: 141.13169ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:31.068818Z","caller":"traceutil/trace.go:171","msg":"trace[1698906224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9816; }","duration":"153.752197ms","start":"2026-10-19T02:11:30.915046Z","end":"2026-10-19T02:11:31.068798Z","steps":["trace[1698906224] 'agreement among raft nodes before linearized reading' (duration: 153.493051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:31.068938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.270191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-19T02:11:31.068991Z","caller":"traceutil/trace.go:171","msg":"trace[1904935361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9816; }","duration":"154.342053ms","start":"2026-10-19T02:11:30.914636Z","end":"2026-10-19T02:11:31.068978Z","steps":["trace[1904935361] 'agreement among raft nodes before linearized reading' (duration: 153.807339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:31.069042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.729471ms","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-10-19T02:11:31.069205Z","caller":"traceutil/trace.go:171","msg":"trace[344679064] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9816; }","duration":"140.984827ms","start":"2026-10-19T02:11:30.928188Z","end":"2026-10-19T02:11:31.069173Z","steps":["trace[344679064] 'agreement among raft nodes before linearized reading' (duration: 140.389172ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:31.22365Z","caller":"traceutil/trace.go:171","msg":"trace[989600915] transaction","detail":"{read_only:false; response_revision:9818; number_of_response:1; }","duration":"148.246649ms","start":"2026-10-19T02:11:31.075374Z","end":"2026-10-19T02:11:31.223621Z","steps":["trace[989600915] 'process raft request' (duration: 117.247129ms)","trace[989600915] 'compare' (duration: 30.844406ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:11:31.223827Z","caller":"traceutil/trace.go:171","msg":"trace[674453802] transaction","detail":"{read_only:false; response_revision:9820; number_of_response:1; }","duration":"148.322391ms","start":"2026-10-19T02:11:31.075487Z","end":"2026-10-19T02:11:31.223809Z","steps":["trace[674453802] 'process raft request' (duration: 148.210328ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:31.223848Z","caller":"traceutil/trace.go:171","msg":"trace[1776472722] transaction","detail":"{read_only:false; response_revision:9819; number_of_response:1; }","duration":"148.355892ms","start":"2026-10-19T02:11:31.075446Z","end":"2026-10-19T02:11:31.223802Z","steps":["trace[1776472722] 'process raft request' (duration: 148.131136ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:31.224083Z","caller":"traceutil/trace.go:171","msg":"trace[1290609628] transaction","detail":"{read_only:false; response_revision:9821; number_of_response:1; }","duration":"148.178538ms","start":"2026-10-19T02:11:31.075882Z","end":"2026-10-19T02:11:31.22406Z","steps":["trace[1290609628] 'process raft request' (duration: 147.87962ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:12:54.49368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7998} {"level":"info","ts":"2026-10-19T02:12:54.565565Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7998,"took":"69.711445ms","hash":1616844383,"current-db-size-bytes":56381440,"current-db-size":"56 MB","current-db-size-in-use-bytes":28483584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-19T02:12:54.565648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1616844383,"revision":7998,"compact-revision":5808} {"level":"info","ts":"2026-10-19T02:16:49.286249Z","caller":"traceutil/trace.go:171","msg":"trace[113788992] transaction","detail":"{read_only:false; response_revision:12130; number_of_response:1; }","duration":"141.487089ms","start":"2026-10-19T02:16:49.144725Z","end":"2026-10-19T02:16:49.286213Z","steps":["trace[113788992] 'process raft request' (duration: 141.263913ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:16:49.460052Z","caller":"traceutil/trace.go:171","msg":"trace[121595371] linearizableReadLoop","detail":"{readStateIndex:12556; appliedIndex:12554; }","duration":"287.612134ms","start":"2026-10-19T02:16:49.172395Z","end":"2026-10-19T02:16:49.460007Z","steps":["trace[121595371] 'read index received' (duration: 113.69212ms)","trace[121595371] 'applied index is now lower than readState.Index' (duration: 173.918514ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:16:49.460104Z","caller":"traceutil/trace.go:171","msg":"trace[22991272] transaction","detail":"{read_only:false; response_revision:12131; number_of_response:1; }","duration":"311.987878ms","start":"2026-10-19T02:16:49.148012Z","end":"2026-10-19T02:16:49.46Z","steps":["trace[22991272] 'process raft request' (duration: 273.207133ms)","trace[22991272] 'compare' (duration: 38.288772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:16:49.46029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.118293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-19T02:16:49.460334Z","caller":"traceutil/trace.go:171","msg":"trace[754194194] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12132; }","duration":"266.182215ms","start":"2026-10-19T02:16:49.194141Z","end":"2026-10-19T02:16:49.460323Z","steps":["trace[754194194] 'agreement among raft nodes before linearized reading' (duration: 266.024721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:16:49.460369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:16:49.147994Z","time spent":"312.231604ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:16:49.460601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.192898ms","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-10-19T02:16:49.460713Z","caller":"traceutil/trace.go:171","msg":"trace[225880932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12132; }","duration":"288.315611ms","start":"2026-10-19T02:16:49.172374Z","end":"2026-10-19T02:16:49.46069Z","steps":["trace[225880932] 'agreement among raft nodes before linearized reading' (duration: 287.744307ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:17:54.501784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10392} {"level":"info","ts":"2026-10-19T02:17:54.592256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10392,"took":"87.680746ms","hash":1624054421,"current-db-size-bytes":56381440,"current-db-size":"56 MB","current-db-size-in-use-bytes":25862144,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-19T02:17:54.592337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1624054421,"revision":10392,"compact-revision":7998} {"level":"info","ts":"2026-10-19T02:22:54.517584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12565} {"level":"info","ts":"2026-10-19T02:22:54.616193Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12565,"took":"95.335086ms","hash":652146338,"current-db-size-bytes":56381440,"current-db-size":"56 MB","current-db-size-in-use-bytes":27848704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-19T02:22:54.616303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":652146338,"revision":12565,"compact-revision":10392} {"level":"warn","ts":"2026-10-19T02:23:00.35614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.815642ms","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-10-19T02:23:00.356253Z","caller":"traceutil/trace.go:171","msg":"trace[1445294213] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14637; }","duration":"124.000736ms","start":"2026-10-19T02:23:00.232227Z","end":"2026-10-19T02:23:00.356227Z","steps":["trace[1445294213] 'range keys from in-memory index tree' (duration: 123.472103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:23:25.953165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.108994ms","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-10-19T02:23:25.953298Z","caller":"traceutil/trace.go:171","msg":"trace[429035379] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:14774; }","duration":"322.334639ms","start":"2026-10-19T02:23:25.630936Z","end":"2026-10-19T02:23:25.953271Z","steps":["trace[429035379] 'count revisions from in-memory index tree' (duration: 321.972551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:23:25.953349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:23:25.630905Z","time spent":"322.431242ms","remote":"127.0.0.1:39320","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-10-19T02:23:25.953867Z","caller":"traceutil/trace.go:171","msg":"trace[329008104] transaction","detail":"{read_only:false; response_revision:14775; number_of_response:1; }","duration":"303.087436ms","start":"2026-10-19T02:23:25.65074Z","end":"2026-10-19T02:23:25.953827Z","steps":["trace[329008104] 'process raft request' (duration: 300.06726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:23:25.954107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:23:25.650709Z","time spent":"303.2744ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:23:44.368359Z","caller":"traceutil/trace.go:171","msg":"trace[973095415] linearizableReadLoop","detail":"{readStateIndex:15403; appliedIndex:15402; }","duration":"320.726271ms","start":"2026-10-19T02:23:44.047601Z","end":"2026-10-19T02:23:44.368328Z","steps":["trace[973095415] 'read index received' (duration: 320.549397ms)","trace[973095415] 'applied index is now lower than readState.Index' (duration: 174.854µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:23:44.36842Z","caller":"traceutil/trace.go:171","msg":"trace[1854781677] transaction","detail":"{read_only:false; response_revision:14881; number_of_response:1; }","duration":"413.831251ms","start":"2026-10-19T02:23:43.954545Z","end":"2026-10-19T02:23:44.368376Z","steps":["trace[1854781677] 'process raft request' (duration: 413.555004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:23:44.368605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.968927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:23:44.368844Z","caller":"traceutil/trace.go:171","msg":"trace[141249607] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:14881; }","duration":"321.286284ms","start":"2026-10-19T02:23:44.047533Z","end":"2026-10-19T02:23:44.368819Z","steps":["trace[141249607] 'agreement among raft nodes before linearized reading' (duration: 320.959486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:23:44.368918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:23:44.047507Z","time spent":"321.390017ms","remote":"127.0.0.1:39328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-10-19T02:23:44.368923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:23:43.954512Z","time spent":"414.167258ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:23:44.377724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.177474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T02:23:44.377763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.892608ms","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-10-19T02:23:44.377836Z","caller":"traceutil/trace.go:171","msg":"trace[964371106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14882; }","duration":"304.329558ms","start":"2026-10-19T02:23:44.073473Z","end":"2026-10-19T02:23:44.377803Z","steps":["trace[964371106] 'agreement among raft nodes before linearized reading' (duration: 304.112232ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:23:44.377862Z","caller":"traceutil/trace.go:171","msg":"trace[1799960376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14882; }","duration":"320.033111ms","start":"2026-10-19T02:23:44.057805Z","end":"2026-10-19T02:23:44.377838Z","steps":["trace[1799960376] 'agreement among raft nodes before linearized reading' (duration: 319.728664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:23:44.377903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:23:44.073463Z","time spent":"304.429531ms","remote":"127.0.0.1:49394","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-10-19T02:23:44.377913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:23:44.057789Z","time spent":"320.114694ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-19T02:23:44.377884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.767797ms","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-10-19T02:23:44.378028Z","caller":"traceutil/trace.go:171","msg":"trace[1242493453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14882; }","duration":"319.967921ms","start":"2026-10-19T02:23:44.058028Z","end":"2026-10-19T02:23:44.377996Z","steps":["trace[1242493453] 'agreement among raft nodes before linearized reading' (duration: 319.649623ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:23:44.378019Z","caller":"traceutil/trace.go:171","msg":"trace[169248221] transaction","detail":"{read_only:false; response_revision:14882; number_of_response:1; }","duration":"145.106756ms","start":"2026-10-19T02:23:44.232869Z","end":"2026-10-19T02:23:44.377976Z","steps":["trace[169248221] 'process raft request' (duration: 144.427509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:23:44.378095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:23:44.058007Z","time spent":"320.076844ms","remote":"127.0.0.1:49694","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-10-19T02:23:44.378046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.982394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-19T02:23:44.378219Z","caller":"traceutil/trace.go:171","msg":"trace[322147743] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14882; }","duration":"309.18378ms","start":"2026-10-19T02:23:44.069013Z","end":"2026-10-19T02:23:44.378197Z","steps":["trace[322147743] 'agreement among raft nodes before linearized reading' (duration: 308.626565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:23:44.378284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:23:44.069002Z","time spent":"309.269672ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-19T02:24:07.980436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.81142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-19T02:24:07.980532Z","caller":"traceutil/trace.go:171","msg":"trace[125483499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15202; }","duration":"158.967914ms","start":"2026-10-19T02:24:07.821544Z","end":"2026-10-19T02:24:07.980512Z","steps":["trace[125483499] 'range keys from in-memory index tree' (duration: 158.623285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:24:07.980445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.424619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91371cdf-b835-4afd-9a5e-c584812df9d1\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-10-19T02:24:07.980615Z","caller":"traceutil/trace.go:171","msg":"trace[154175470] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91371cdf-b835-4afd-9a5e-c584812df9d1; range_end:; response_count:1; response_revision:15202; }","duration":"179.637725ms","start":"2026-10-19T02:24:07.800957Z","end":"2026-10-19T02:24:07.980595Z","steps":["trace[154175470] 'range keys from in-memory index tree' (duration: 179.234814ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:24:08.421013Z","caller":"traceutil/trace.go:171","msg":"trace[1091683643] transaction","detail":"{read_only:false; response_revision:15203; number_of_response:1; }","duration":"435.803281ms","start":"2026-10-19T02:24:07.985178Z","end":"2026-10-19T02:24:08.420981Z","steps":["trace[1091683643] 'process raft request' (duration: 432.916549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:24:08.42122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:24:07.985152Z","time spent":"435.960806ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:24:08.424319Z","caller":"traceutil/trace.go:171","msg":"trace[240877881] linearizableReadLoop","detail":"{readStateIndex:15740; appliedIndex:15738; }","duration":"357.70131ms","start":"2026-10-19T02:24:08.066592Z","end":"2026-10-19T02:24:08.424293Z","steps":["trace[240877881] 'read index received' (duration: 351.522864ms)","trace[240877881] 'applied index is now lower than readState.Index' (duration: 6.177326ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:24:08.424358Z","caller":"traceutil/trace.go:171","msg":"trace[1858225500] transaction","detail":"{read_only:false; response_revision:15204; number_of_response:1; }","duration":"425.932105ms","start":"2026-10-19T02:24:07.998392Z","end":"2026-10-19T02:24:08.424324Z","steps":["trace[1858225500] 'process raft request' (duration: 425.75779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:24:08.424521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:24:07.998373Z","time spent":"426.050727ms","remote":"127.0.0.1:49446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:24:08.424658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.628533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-j9mmw\" ","response":"range_response_count:1 size:20807"} {"level":"info","ts":"2026-10-19T02:24:08.424695Z","caller":"traceutil/trace.go:171","msg":"trace[594175054] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-j9mmw; range_end:; response_count:1; response_revision:15204; }","duration":"206.696675ms","start":"2026-10-19T02:24:08.217988Z","end":"2026-10-19T02:24:08.424685Z","steps":["trace[594175054] 'agreement among raft nodes before linearized reading' (duration: 206.573452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:24:08.424533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.927635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:24:08.424759Z","caller":"traceutil/trace.go:171","msg":"trace[2051933206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15204; }","duration":"358.207462ms","start":"2026-10-19T02:24:08.066539Z","end":"2026-10-19T02:24:08.424746Z","steps":["trace[2051933206] 'agreement among raft nodes before linearized reading' (duration: 357.896134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:24:08.424801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:24:08.066515Z","time spent":"358.276013ms","remote":"127.0.0.1:49394","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-10-19T02:24:08.424997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.617161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91371cdf-b835-4afd-9a5e-c584812df9d1\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-10-19T02:24:08.425035Z","caller":"traceutil/trace.go:171","msg":"trace[1028175862] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91371cdf-b835-4afd-9a5e-c584812df9d1; range_end:; response_count:1; response_revision:15204; }","duration":"116.699113ms","start":"2026-10-19T02:24:08.308326Z","end":"2026-10-19T02:24:08.425025Z","steps":["trace[1028175862] 'agreement among raft nodes before linearized reading' (duration: 116.57566ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:25:30.231483Z","caller":"traceutil/trace.go:171","msg":"trace[1947785433] transaction","detail":"{read_only:false; response_revision:15992; number_of_response:1; }","duration":"131.075613ms","start":"2026-10-19T02:25:30.100358Z","end":"2026-10-19T02:25:30.231434Z","steps":["trace[1947785433] 'process raft request' (duration: 130.680133ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:27:54.526124Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14600} {"level":"info","ts":"2026-10-19T02:27:54.613761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14600,"took":"84.276448ms","hash":3328732373,"current-db-size-bytes":56381440,"current-db-size":"56 MB","current-db-size-in-use-bytes":31399936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-19T02:27:54.613837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3328732373,"revision":14600,"compact-revision":12565} {"level":"warn","ts":"2026-10-19T02:31:30.951949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.508457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12433"} {"level":"warn","ts":"2026-10-19T02:31:30.95239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.805908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-19T02:31:30.95248Z","caller":"traceutil/trace.go:171","msg":"trace[5706256] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18628; }","duration":"139.963078ms","start":"2026-10-19T02:31:30.812347Z","end":"2026-10-19T02:31:30.95231Z","steps":["trace[5706256] 'range keys from in-memory index tree' (duration: 139.173048ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:31:30.952501Z","caller":"traceutil/trace.go:171","msg":"trace[204378526] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18628; }","duration":"134.985754ms","start":"2026-10-19T02:31:30.817489Z","end":"2026-10-19T02:31:30.952474Z","steps":["trace[204378526] 'range keys from in-memory index tree' (duration: 134.477361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:31:37.906784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.132108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:31:37.906893Z","caller":"traceutil/trace.go:171","msg":"trace[168037732] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:18673; }","duration":"151.320763ms","start":"2026-10-19T02:31:37.755545Z","end":"2026-10-19T02:31:37.906865Z","steps":["trace[168037732] 'count revisions from in-memory index tree' (duration: 151.025276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:31:38.354121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.354867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-j9mmw\" ","response":"range_response_count:1 size:20910"} {"level":"info","ts":"2026-10-19T02:31:38.354237Z","caller":"traceutil/trace.go:171","msg":"trace[111964728] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-j9mmw; range_end:; response_count:1; response_revision:18674; }","duration":"167.565262ms","start":"2026-10-19T02:31:38.186641Z","end":"2026-10-19T02:31:38.354206Z","steps":["trace[111964728] 'range keys from in-memory index tree' (duration: 167.009717ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:31:38.354419Z","caller":"traceutil/trace.go:171","msg":"trace[793431046] transaction","detail":"{read_only:false; response_revision:18675; number_of_response:1; }","duration":"100.420864ms","start":"2026-10-19T02:31:38.253911Z","end":"2026-10-19T02:31:38.354332Z","steps":["trace[793431046] 'process raft request' (duration: 86.74109ms)","trace[793431046] 'compare' (duration: 13.420108ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:31:38.843733Z","caller":"traceutil/trace.go:171","msg":"trace[487038027] transaction","detail":"{read_only:false; response_revision:18676; number_of_response:1; }","duration":"466.415594ms","start":"2026-10-19T02:31:38.377171Z","end":"2026-10-19T02:31:38.843587Z","steps":["trace[487038027] 'process raft request' (duration: 440.886362ms)","trace[487038027] 'compare' (duration: 24.546817ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:31:38.844728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:31:38.377143Z","time spent":"467.496041ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21025,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:31:38.845419Z","caller":"traceutil/trace.go:171","msg":"trace[252196864] linearizableReadLoop","detail":"{readStateIndex:19325; appliedIndex:19323; }","duration":"300.177355ms","start":"2026-10-19T02:31:38.545189Z","end":"2026-10-19T02:31:38.845366Z","steps":["trace[252196864] 'read index received' (duration: 272.887988ms)","trace[252196864] 'applied index is now lower than readState.Index' (duration: 27.288077ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:31:38.845543Z","caller":"traceutil/trace.go:171","msg":"trace[1537034051] transaction","detail":"{read_only:false; response_revision:18677; number_of_response:1; }","duration":"464.881295ms","start":"2026-10-19T02:31:38.380582Z","end":"2026-10-19T02:31:38.845463Z","steps":["trace[1537034051] 'process raft request' (duration: 463.687485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:31:38.845673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.471422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-19T02:31:38.845718Z","caller":"traceutil/trace.go:171","msg":"trace[252493926] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18677; }","duration":"300.573505ms","start":"2026-10-19T02:31:38.54513Z","end":"2026-10-19T02:31:38.845704Z","steps":["trace[252493926] 'agreement among raft nodes before linearized reading' (duration: 300.40596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:31:38.845751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:31:38.545108Z","time spent":"300.635136ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-19T02:31:38.845799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:31:38.38055Z","time spent":"465.12376ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:31:38.846188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.02996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pllt9\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-10-19T02:31:38.846603Z","caller":"traceutil/trace.go:171","msg":"trace[656059963] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pllt9; range_end:; response_count:1; response_revision:18677; }","duration":"103.45253ms","start":"2026-10-19T02:31:38.742965Z","end":"2026-10-19T02:31:38.846418Z","steps":["trace[656059963] 'agreement among raft nodes before linearized reading' (duration: 102.623079ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:31:39.140389Z","caller":"traceutil/trace.go:171","msg":"trace[40053800] linearizableReadLoop","detail":"{readStateIndex:19326; appliedIndex:19325; }","duration":"284.48247ms","start":"2026-10-19T02:31:38.855873Z","end":"2026-10-19T02:31:39.140355Z","steps":["trace[40053800] 'read index received' (duration: 284.390268ms)","trace[40053800] 'applied index is now lower than readState.Index' (duration: 90.922µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:31:39.140526Z","caller":"traceutil/trace.go:171","msg":"trace[9947579] transaction","detail":"{read_only:false; response_revision:18678; number_of_response:1; }","duration":"288.607744ms","start":"2026-10-19T02:31:38.851881Z","end":"2026-10-19T02:31:39.140489Z","steps":["trace[9947579] 'process raft request' (duration: 288.259976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:31:39.14089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.967524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-19T02:31:39.141037Z","caller":"traceutil/trace.go:171","msg":"trace[2071068235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18678; }","duration":"106.135558ms","start":"2026-10-19T02:31:39.034835Z","end":"2026-10-19T02:31:39.14097Z","steps":["trace[2071068235] 'agreement among raft nodes before linearized reading' (duration: 105.704867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:31:39.142554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.639035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:949044"} {"level":"info","ts":"2026-10-19T02:31:39.142695Z","caller":"traceutil/trace.go:171","msg":"trace[745952513] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18678; }","duration":"286.86296ms","start":"2026-10-19T02:31:38.8558Z","end":"2026-10-19T02:31:39.142662Z","steps":["trace[745952513] 'agreement among raft nodes before linearized reading' (duration: 284.726346ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:32:35.664902Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2af340d70e332c3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T02:32:35.670606Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-19T02:32:35.670754Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-19T02:32:54.537461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16974} {"level":"info","ts":"2026-10-19T02:32:54.667347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16974,"took":"123.095014ms","hash":3718374336,"current-db-size-bytes":56381440,"current-db-size":"56 MB","current-db-size-in-use-bytes":33304576,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-19T02:32:54.667449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3718374336,"revision":16974,"compact-revision":14600} {"level":"info","ts":"2026-10-19T02:37:11.232769Z","caller":"traceutil/trace.go:171","msg":"trace[160777464] transaction","detail":"{read_only:false; response_revision:21282; number_of_response:1; }","duration":"150.226437ms","start":"2026-10-19T02:37:11.08251Z","end":"2026-10-19T02:37:11.232737Z","steps":["trace[160777464] 'process raft request' (duration: 150.011041ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:11.484607Z","caller":"traceutil/trace.go:171","msg":"trace[623470033] transaction","detail":"{read_only:false; response_revision:21283; number_of_response:1; }","duration":"246.855975ms","start":"2026-10-19T02:37:11.237705Z","end":"2026-10-19T02:37:11.484561Z","steps":["trace[623470033] 'process raft request' (duration: 163.398427ms)","trace[623470033] 'compare' (duration: 83.286303ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:37:11.49822Z","caller":"traceutil/trace.go:171","msg":"trace[402279727] linearizableReadLoop","detail":"{readStateIndex:22017; appliedIndex:22015; }","duration":"110.686812ms","start":"2026-10-19T02:37:11.387497Z","end":"2026-10-19T02:37:11.498184Z","steps":["trace[402279727] 'read index received' (duration: 13.631363ms)","trace[402279727] 'applied index is now lower than readState.Index' (duration: 97.054039ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:37:11.498331Z","caller":"traceutil/trace.go:171","msg":"trace[1566376988] transaction","detail":"{read_only:false; response_revision:21284; number_of_response:1; }","duration":"259.347118ms","start":"2026-10-19T02:37:11.238927Z","end":"2026-10-19T02:37:11.498274Z","steps":["trace[1566376988] 'process raft request' (duration: 259.077281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:11.498537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.03616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-19T02:37:11.4986Z","caller":"traceutil/trace.go:171","msg":"trace[842678692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21284; }","duration":"111.172994ms","start":"2026-10-19T02:37:11.387411Z","end":"2026-10-19T02:37:11.498584Z","steps":["trace[842678692] 'agreement among raft nodes before linearized reading' (duration: 110.953769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:11.498621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.119862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-19T02:37:11.498698Z","caller":"traceutil/trace.go:171","msg":"trace[1983838402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21284; }","duration":"111.265007ms","start":"2026-10-19T02:37:11.38741Z","end":"2026-10-19T02:37:11.498675Z","steps":["trace[1983838402] 'agreement among raft nodes before linearized reading' (duration: 111.00626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:27.726565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.379392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-19T02:37:27.726655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.733886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-19T02:37:27.726739Z","caller":"traceutil/trace.go:171","msg":"trace[374983318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21364; }","duration":"142.839829ms","start":"2026-10-19T02:37:27.583873Z","end":"2026-10-19T02:37:27.726713Z","steps":["trace[374983318] 'range keys from in-memory index tree' (duration: 142.568682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:27.726924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.983973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-19T02:37:27.726954Z","caller":"traceutil/trace.go:171","msg":"trace[918826813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21364; }","duration":"153.060024ms","start":"2026-10-19T02:37:27.573884Z","end":"2026-10-19T02:37:27.726944Z","steps":["trace[918826813] 'range keys from in-memory index tree' (duration: 152.8619ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:27.727152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.603303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-19T02:37:27.726674Z","caller":"traceutil/trace.go:171","msg":"trace[774656497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21364; }","duration":"145.524306ms","start":"2026-10-19T02:37:27.581128Z","end":"2026-10-19T02:37:27.726653Z","steps":["trace[774656497] 'range keys from in-memory index tree' (duration: 145.192068ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:27.727181Z","caller":"traceutil/trace.go:171","msg":"trace[1473365325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21364; }","duration":"142.650153ms","start":"2026-10-19T02:37:27.584524Z","end":"2026-10-19T02:37:27.727174Z","steps":["trace[1473365325] 'range keys from in-memory index tree' (duration: 142.553701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:27.726987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.367318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:37:27.729035Z","caller":"traceutil/trace.go:171","msg":"trace[1329933385] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:21364; }","duration":"283.44735ms","start":"2026-10-19T02:37:27.445525Z","end":"2026-10-19T02:37:27.728973Z","steps":["trace[1329933385] 'count revisions from in-memory index tree' (duration: 281.222104ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:27.952355Z","caller":"traceutil/trace.go:171","msg":"trace[1184218339] transaction","detail":"{read_only:false; response_revision:21367; number_of_response:1; }","duration":"218.91243ms","start":"2026-10-19T02:37:27.733417Z","end":"2026-10-19T02:37:27.952329Z","steps":["trace[1184218339] 'process raft request' (duration: 218.782137ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:27.952367Z","caller":"traceutil/trace.go:171","msg":"trace[416692887] transaction","detail":"{read_only:false; response_revision:21366; number_of_response:1; }","duration":"218.96733ms","start":"2026-10-19T02:37:27.733349Z","end":"2026-10-19T02:37:27.952316Z","steps":["trace[416692887] 'process raft request' (duration: 186.061633ms)","trace[416692887] 'compare' (duration: 32.172119ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:37:27.952638Z","caller":"traceutil/trace.go:171","msg":"trace[701807613] transaction","detail":"{read_only:false; response_revision:21368; number_of_response:1; }","duration":"219.170444ms","start":"2026-10-19T02:37:27.733458Z","end":"2026-10-19T02:37:27.952628Z","steps":["trace[701807613] 'process raft request' (duration: 218.814786ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:27.95264Z","caller":"traceutil/trace.go:171","msg":"trace[124383642] linearizableReadLoop","detail":"{readStateIndex:22105; appliedIndex:22101; }","duration":"213.634906ms","start":"2026-10-19T02:37:27.738936Z","end":"2026-10-19T02:37:27.952571Z","steps":["trace[124383642] 'read index received' (duration: 10.123154ms)","trace[124383642] 'applied index is now lower than readState.Index' (duration: 203.509692ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:37:27.953281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.326734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-19T02:37:27.953403Z","caller":"traceutil/trace.go:171","msg":"trace[1096948306] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21368; }","duration":"214.507528ms","start":"2026-10-19T02:37:27.738867Z","end":"2026-10-19T02:37:27.953375Z","steps":["trace[1096948306] 'agreement among raft nodes before linearized reading' (duration: 213.899023ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:28.128978Z","caller":"traceutil/trace.go:171","msg":"trace[939789580] linearizableReadLoop","detail":"{readStateIndex:22106; appliedIndex:22105; }","duration":"117.941502ms","start":"2026-10-19T02:37:28.011004Z","end":"2026-10-19T02:37:28.128945Z","steps":["trace[939789580] 'read index received' (duration: 112.883815ms)","trace[939789580] 'applied index is now lower than readState.Index' (duration: 5.055697ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:37:28.129189Z","caller":"traceutil/trace.go:171","msg":"trace[759992765] transaction","detail":"{read_only:false; response_revision:21369; number_of_response:1; }","duration":"171.489977ms","start":"2026-10-19T02:37:27.957534Z","end":"2026-10-19T02:37:28.129024Z","steps":["trace[759992765] 'process raft request' (duration: 166.41658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:28.129331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.29451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.102\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-19T02:37:28.129458Z","caller":"traceutil/trace.go:171","msg":"trace[942809635] range","detail":"{range_begin:/registry/masterleases/162.253.55.102; range_end:; response_count:1; response_revision:21369; }","duration":"118.465005ms","start":"2026-10-19T02:37:28.010961Z","end":"2026-10-19T02:37:28.129426Z","steps":["trace[942809635] 'agreement among raft nodes before linearized reading' (duration: 118.114966ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:28.587726Z","caller":"traceutil/trace.go:171","msg":"trace[1608757976] transaction","detail":"{read_only:false; response_revision:21370; number_of_response:1; }","duration":"226.0783ms","start":"2026-10-19T02:37:28.361551Z","end":"2026-10-19T02:37:28.587629Z","steps":["trace[1608757976] 'process raft request' (duration: 184.249559ms)","trace[1608757976] 'compare' (duration: 41.343859ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:37:28.924503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.920561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:37:28.92491Z","caller":"traceutil/trace.go:171","msg":"trace[1986532026] transaction","detail":"{read_only:false; response_revision:21371; number_of_response:1; }","duration":"268.385342ms","start":"2026-10-19T02:37:28.656476Z","end":"2026-10-19T02:37:28.924862Z","steps":["trace[1986532026] 'process raft request' (duration: 77.885886ms)","trace[1986532026] 'compare' (duration: 189.544293ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:37:54.549538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19485} {"level":"info","ts":"2026-10-19T02:37:54.682411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19485,"took":"128.350495ms","hash":3491838670,"current-db-size-bytes":56381440,"current-db-size":"56 MB","current-db-size-in-use-bytes":29032448,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-19T02:37:54.682511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3491838670,"revision":19485,"compact-revision":16974} {"level":"info","ts":"2026-10-19T02:41:11.203565Z","caller":"traceutil/trace.go:171","msg":"trace[21014071] transaction","detail":"{read_only:false; response_revision:23153; number_of_response:1; }","duration":"151.29922ms","start":"2026-10-19T02:41:11.052242Z","end":"2026-10-19T02:41:11.203542Z","steps":["trace[21014071] 'process raft request' (duration: 151.052284ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:11.203523Z","caller":"traceutil/trace.go:171","msg":"trace[1799806273] linearizableReadLoop","detail":"{readStateIndex:23955; appliedIndex:23954; }","duration":"135.859112ms","start":"2026-10-19T02:41:11.067628Z","end":"2026-10-19T02:41:11.203487Z","steps":["trace[1799806273] 'read index received' (duration: 135.653707ms)","trace[1799806273] 'applied index is now lower than readState.Index' (duration: 204.265µs)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:41:11.203718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.055407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:41:11.203892Z","caller":"traceutil/trace.go:171","msg":"trace[1132026484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23153; }","duration":"136.286993ms","start":"2026-10-19T02:41:11.067559Z","end":"2026-10-19T02:41:11.203846Z","steps":["trace[1132026484] 'agreement among raft nodes before linearized reading' (duration: 136.018936ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:35.642189Z","caller":"traceutil/trace.go:171","msg":"trace[1335825307] transaction","detail":"{read_only:false; response_revision:23894; number_of_response:1; }","duration":"234.569932ms","start":"2026-10-19T02:41:35.407564Z","end":"2026-10-19T02:41:35.642134Z","steps":["trace[1335825307] 'process raft request' (duration: 213.211735ms)","trace[1335825307] 'compare' (duration: 18.854244ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:41:35.642739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.89687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-19T02:41:35.642864Z","caller":"traceutil/trace.go:171","msg":"trace[394744090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23894; }","duration":"225.126146ms","start":"2026-10-19T02:41:35.41772Z","end":"2026-10-19T02:41:35.642846Z","steps":["trace[394744090] 'agreement among raft nodes before linearized reading' (duration: 224.610293ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:35.64228Z","caller":"traceutil/trace.go:171","msg":"trace[1592712126] linearizableReadLoop","detail":"{readStateIndex:24711; appliedIndex:24710; }","duration":"224.456389ms","start":"2026-10-19T02:41:35.417791Z","end":"2026-10-19T02:41:35.642247Z","steps":["trace[1592712126] 'read index received' (duration: 202.906917ms)","trace[1592712126] 'applied index is now lower than readState.Index' (duration: 21.159142ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:41:35.644712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.428474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-19T02:41:35.64476Z","caller":"traceutil/trace.go:171","msg":"trace[1628918467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23894; }","duration":"102.476445ms","start":"2026-10-19T02:41:35.542271Z","end":"2026-10-19T02:41:35.644747Z","steps":["trace[1628918467] 'agreement among raft nodes before linearized reading' (duration: 101.603853ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:42:13.252152Z","caller":"traceutil/trace.go:171","msg":"trace[517957711] linearizableReadLoop","detail":"{readStateIndex:25086; appliedIndex:25085; }","duration":"165.611151ms","start":"2026-10-19T02:42:13.086487Z","end":"2026-10-19T02:42:13.252098Z","steps":["trace[517957711] 'read index received' (duration: 149.479575ms)","trace[517957711] 'applied index is now lower than readState.Index' (duration: 16.129596ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:42:13.252337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.810685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-19T02:42:13.2524Z","caller":"traceutil/trace.go:171","msg":"trace[606341957] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24259; }","duration":"165.931758ms","start":"2026-10-19T02:42:13.086452Z","end":"2026-10-19T02:42:13.252384Z","steps":["trace[606341957] 'agreement among raft nodes before linearized reading' (duration: 165.785515ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:42:54.557542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21508} {"level":"info","ts":"2026-10-19T02:42:54.679212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21508,"took":"116.344553ms","hash":2390022485,"current-db-size-bytes":57225216,"current-db-size":"57 MB","current-db-size-in-use-bytes":43216896,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-19T02:42:54.679283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2390022485,"revision":21508,"compact-revision":19485} {"level":"info","ts":"2026-10-19T02:43:16.56942Z","caller":"traceutil/trace.go:171","msg":"trace[1369974814] linearizableReadLoop","detail":"{readStateIndex:25793; appliedIndex:25791; }","duration":"176.095044ms","start":"2026-10-19T02:43:16.393289Z","end":"2026-10-19T02:43:16.569384Z","steps":["trace[1369974814] 'read index received' (duration: 13.241462ms)","trace[1369974814] 'applied index is now lower than readState.Index' (duration: 162.852452ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:43:16.569672Z","caller":"traceutil/trace.go:171","msg":"trace[24134641] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"177.043238ms","start":"2026-10-19T02:43:16.392587Z","end":"2026-10-19T02:43:16.56963Z","steps":["trace[24134641] 'process raft request' (duration: 176.618757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:43:16.569926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.590715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-19T02:43:16.570037Z","caller":"traceutil/trace.go:171","msg":"trace[1312598511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24949; }","duration":"176.76757ms","start":"2026-10-19T02:43:16.393245Z","end":"2026-10-19T02:43:16.570012Z","steps":["trace[1312598511] 'agreement among raft nodes before linearized reading' (duration: 176.423862ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:43:16.659616Z","caller":"traceutil/trace.go:171","msg":"trace[1427645726] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"198.756813ms","start":"2026-10-19T02:43:16.46083Z","end":"2026-10-19T02:43:16.659586Z","steps":["trace[1427645726] 'process raft request' (duration: 198.502467ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:43:16.659916Z","caller":"traceutil/trace.go:171","msg":"trace[1084100387] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"169.668392ms","start":"2026-10-19T02:43:16.490227Z","end":"2026-10-19T02:43:16.659896Z","steps":["trace[1084100387] 'process raft request' (duration: 169.308613ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:43:16.660534Z","caller":"traceutil/trace.go:171","msg":"trace[95189928] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"146.543601ms","start":"2026-10-19T02:43:16.513956Z","end":"2026-10-19T02:43:16.660499Z","steps":["trace[95189928] 'process raft request' (duration: 145.863464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:43:16.660837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.600866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-19T02:43:16.660956Z","caller":"traceutil/trace.go:171","msg":"trace[722577243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24952; }","duration":"250.779569ms","start":"2026-10-19T02:43:16.410152Z","end":"2026-10-19T02:43:16.660931Z","steps":["trace[722577243] 'agreement among raft nodes before linearized reading' (duration: 250.472931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:43:16.660871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.986798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-19T02:43:16.661067Z","caller":"traceutil/trace.go:171","msg":"trace[1413288213] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24952; }","duration":"109.256865ms","start":"2026-10-19T02:43:16.551772Z","end":"2026-10-19T02:43:16.661029Z","steps":["trace[1413288213] 'agreement among raft nodes before linearized reading' (duration: 108.910076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:43:16.661404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.77305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-19T02:43:16.661603Z","caller":"traceutil/trace.go:171","msg":"trace[1314041481] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24952; }","duration":"251.320044ms","start":"2026-10-19T02:43:16.410249Z","end":"2026-10-19T02:43:16.661569Z","steps":["trace[1314041481] 'agreement among raft nodes before linearized reading' (duration: 250.393181ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:43:16.684588Z","caller":"traceutil/trace.go:171","msg":"trace[1419631366] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"106.136926ms","start":"2026-10-19T02:43:16.578428Z","end":"2026-10-19T02:43:16.684565Z","steps":["trace[1419631366] 'process raft request' (duration: 106.078226ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:43:16.68517Z","caller":"traceutil/trace.go:171","msg":"trace[1953452008] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"109.639784ms","start":"2026-10-19T02:43:16.575472Z","end":"2026-10-19T02:43:16.685112Z","steps":["trace[1953452008] 'process raft request' (duration: 108.897086ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:44:24.118564Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000671a.wal"} {"level":"warn","ts":"2026-10-19T02:45:03.698354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.307025ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-19T02:45:03.698481Z","caller":"traceutil/trace.go:171","msg":"trace[1164663001] linearizableReadLoop","detail":"{readStateIndex:26799; appliedIndex:26798; }","duration":"263.376656ms","start":"2026-10-19T02:45:03.43508Z","end":"2026-10-19T02:45:03.698457Z","steps":["trace[1164663001] 'read index received' (duration: 117.883µs)","trace[1164663001] 'applied index is now lower than readState.Index' (duration: 263.257193ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:45:03.698758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.649923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-19T02:45:03.6988Z","caller":"traceutil/trace.go:171","msg":"trace[2031764586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25928; }","duration":"263.753856ms","start":"2026-10-19T02:45:03.435033Z","end":"2026-10-19T02:45:03.698787Z","steps":["trace[2031764586] 'agreement among raft nodes before linearized reading' (duration: 263.552861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:03.700226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.210227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:45:03.70029Z","caller":"traceutil/trace.go:171","msg":"trace[334617355] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25929; }","duration":"256.30199ms","start":"2026-10-19T02:45:03.443971Z","end":"2026-10-19T02:45:03.700273Z","steps":["trace[334617355] 'agreement among raft nodes before linearized reading' (duration: 256.188837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:03.700535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.948628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-19T02:45:03.700572Z","caller":"traceutil/trace.go:171","msg":"trace[536006875] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25929; }","duration":"239.985599ms","start":"2026-10-19T02:45:03.460575Z","end":"2026-10-19T02:45:03.70056Z","steps":["trace[536006875] 'agreement among raft nodes before linearized reading' (duration: 239.888137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:03.700642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.293443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-19T02:45:03.700756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.471781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-19T02:45:03.700777Z","caller":"traceutil/trace.go:171","msg":"trace[1669614539] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25929; }","duration":"175.506169ms","start":"2026-10-19T02:45:03.525241Z","end":"2026-10-19T02:45:03.700748Z","steps":["trace[1669614539] 'agreement among raft nodes before linearized reading' (duration: 175.213761ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:03.701043Z","caller":"traceutil/trace.go:171","msg":"trace[1464419202] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"255.614121ms","start":"2026-10-19T02:45:03.445417Z","end":"2026-10-19T02:45:03.701032Z","steps":["trace[1464419202] 'process raft request' (duration: 254.583866ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:03.700787Z","caller":"traceutil/trace.go:171","msg":"trace[495811061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25929; }","duration":"240.525213ms","start":"2026-10-19T02:45:03.460253Z","end":"2026-10-19T02:45:03.700778Z","steps":["trace[495811061] 'agreement among raft nodes before linearized reading' (duration: 240.43459ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:04.063412Z","caller":"traceutil/trace.go:171","msg":"trace[1672960310] linearizableReadLoop","detail":"{readStateIndex:26804; appliedIndex:26803; }","duration":"260.464423ms","start":"2026-10-19T02:45:03.802902Z","end":"2026-10-19T02:45:04.063367Z","steps":["trace[1672960310] 'read index received' (duration: 233.609418ms)","trace[1672960310] 'applied index is now lower than readState.Index' (duration: 26.852875ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:45:04.063431Z","caller":"traceutil/trace.go:171","msg":"trace[1230967907] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"271.11053ms","start":"2026-10-19T02:45:03.792265Z","end":"2026-10-19T02:45:04.063376Z","steps":["trace[1230967907] 'process raft request' (duration: 244.45119ms)","trace[1230967907] 'compare' (duration: 26.312601ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:45:04.063734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.816862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-19T02:45:04.063859Z","caller":"traceutil/trace.go:171","msg":"trace[870632901] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25933; }","duration":"260.998637ms","start":"2026-10-19T02:45:03.802831Z","end":"2026-10-19T02:45:04.06383Z","steps":["trace[870632901] 'agreement among raft nodes before linearized reading' (duration: 260.669728ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:04.425409Z","caller":"traceutil/trace.go:171","msg":"trace[230957731] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"279.699096ms","start":"2026-10-19T02:45:04.145682Z","end":"2026-10-19T02:45:04.425381Z","steps":["trace[230957731] 'process raft request' (duration: 279.515142ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:04.431007Z","caller":"traceutil/trace.go:171","msg":"trace[1953028211] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"134.689223ms","start":"2026-10-19T02:45:04.296286Z","end":"2026-10-19T02:45:04.430975Z","steps":["trace[1953028211] 'process raft request' (duration: 134.428347ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:04.431049Z","caller":"traceutil/trace.go:171","msg":"trace[1003733838] transaction","detail":"{read_only:false; response_revision:25936; number_of_response:1; }","duration":"131.577456ms","start":"2026-10-19T02:45:04.299433Z","end":"2026-10-19T02:45:04.431011Z","steps":["trace[1003733838] 'process raft request' (duration: 131.455334ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:07.764554Z","caller":"traceutil/trace.go:171","msg":"trace[2063719033] transaction","detail":"{read_only:false; response_revision:26515; number_of_response:1; }","duration":"347.799771ms","start":"2026-10-19T02:46:07.416729Z","end":"2026-10-19T02:46:07.764529Z","steps":["trace[2063719033] 'process raft request' (duration: 347.624476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:07.764716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:07.416691Z","time spent":"347.934314ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:46:07.792281Z","caller":"traceutil/trace.go:171","msg":"trace[1915182975] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"365.795022ms","start":"2026-10-19T02:46:07.426449Z","end":"2026-10-19T02:46:07.792244Z","steps":["trace[1915182975] 'process raft request' (duration: 365.509994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:07.792531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:07.426425Z","time spent":"365.980867ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:46:07.792633Z","caller":"traceutil/trace.go:171","msg":"trace[659981272] transaction","detail":"{read_only:false; response_revision:26517; number_of_response:1; }","duration":"353.942375ms","start":"2026-10-19T02:46:07.438658Z","end":"2026-10-19T02:46:07.792601Z","steps":["trace[659981272] 'process raft request' (duration: 353.534764ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:07.792679Z","caller":"traceutil/trace.go:171","msg":"trace[158798926] linearizableReadLoop","detail":"{readStateIndex:27399; appliedIndex:27398; }","duration":"361.759381ms","start":"2026-10-19T02:46:07.430884Z","end":"2026-10-19T02:46:07.792643Z","steps":["trace[158798926] 'read index received' (duration: 334.036455ms)","trace[158798926] 'applied index is now lower than readState.Index' (duration: 27.721296ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:46:07.792713Z","caller":"traceutil/trace.go:171","msg":"trace[298384015] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"351.436222ms","start":"2026-10-19T02:46:07.441256Z","end":"2026-10-19T02:46:07.792692Z","steps":["trace[298384015] 'process raft request' (duration: 351.216056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:07.792829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:07.43864Z","time spent":"354.085989ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:46:07.792849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:07.441215Z","time spent":"351.570615ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:46:07.792998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.08567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-19T02:46:07.79306Z","caller":"traceutil/trace.go:171","msg":"trace[2058554424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26518; }","duration":"362.212193ms","start":"2026-10-19T02:46:07.430831Z","end":"2026-10-19T02:46:07.793043Z","steps":["trace[2058554424] 'agreement among raft nodes before linearized reading' (duration: 361.946866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:07.793141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:07.430809Z","time spent":"362.319965ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-19T02:46:08.131356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.502202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:46:08.131523Z","caller":"traceutil/trace.go:171","msg":"trace[1135419991] linearizableReadLoop","detail":"{readStateIndex:27402; appliedIndex:27401; }","duration":"143.188119ms","start":"2026-10-19T02:46:07.988317Z","end":"2026-10-19T02:46:08.131505Z","steps":["trace[1135419991] 'read index received' (duration: 39.661µs)","trace[1135419991] 'applied index is now lower than readState.Index' (duration: 143.146798ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:46:08.131624Z","caller":"traceutil/trace.go:171","msg":"trace[1221486844] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"332.961017ms","start":"2026-10-19T02:46:07.798651Z","end":"2026-10-19T02:46:08.131612Z","steps":["trace[1221486844] 'process raft request' (duration: 147.106736ms)","trace[1221486844] 'compare' (duration: 185.390329ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:46:08.13169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:07.798631Z","time spent":"333.025848ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:46:08.132108Z","caller":"traceutil/trace.go:171","msg":"trace[524515745] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"131.995366ms","start":"2026-10-19T02:46:08.000043Z","end":"2026-10-19T02:46:08.132039Z","steps":["trace[524515745] 'process raft request' (duration: 131.415782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:08.132171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.838524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:46:08.132239Z","caller":"traceutil/trace.go:171","msg":"trace[1643584509] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:26520; }","duration":"143.955287ms","start":"2026-10-19T02:46:07.988264Z","end":"2026-10-19T02:46:08.132219Z","steps":["trace[1643584509] 'agreement among raft nodes before linearized reading' (duration: 143.535517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:08.453173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.727543ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-10-19T02:46:08.453292Z","caller":"traceutil/trace.go:171","msg":"trace[325423616] linearizableReadLoop","detail":"{readStateIndex:27404; appliedIndex:27403; }","duration":"309.795406ms","start":"2026-10-19T02:46:08.143473Z","end":"2026-10-19T02:46:08.453269Z","steps":["trace[325423616] 'read index received' (duration: 155.832556ms)","trace[325423616] 'applied index is now lower than readState.Index' (duration: 153.96065ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:46:08.45332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:08.133412Z","time spent":"319.90405ms","remote":"127.0.0.1:49446","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-19T02:46:08.453518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.037121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-19T02:46:08.453572Z","caller":"traceutil/trace.go:171","msg":"trace[112206457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26520; }","duration":"310.125324ms","start":"2026-10-19T02:46:08.143432Z","end":"2026-10-19T02:46:08.453557Z","steps":["trace[112206457] 'agreement among raft nodes before linearized reading' (duration: 309.925779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:08.453609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:08.143418Z","time spent":"310.181426ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-19T02:46:08.70482Z","caller":"traceutil/trace.go:171","msg":"trace[350118562] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"247.318235ms","start":"2026-10-19T02:46:08.457468Z","end":"2026-10-19T02:46:08.704786Z","steps":["trace[350118562] 'process raft request' (duration: 247.264054ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:08.704826Z","caller":"traceutil/trace.go:171","msg":"trace[393090557] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"351.011951ms","start":"2026-10-19T02:46:08.353772Z","end":"2026-10-19T02:46:08.704784Z","steps":["trace[393090557] 'process raft request' (duration: 335.392258ms)","trace[393090557] 'compare' (duration: 14.737781ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:46:08.705202Z","caller":"traceutil/trace.go:171","msg":"trace[384462512] linearizableReadLoop","detail":"{readStateIndex:27405; appliedIndex:27404; }","duration":"251.863389ms","start":"2026-10-19T02:46:08.453324Z","end":"2026-10-19T02:46:08.705187Z","steps":["trace[384462512] 'read index received' (duration: 235.864097ms)","trace[384462512] 'applied index is now lower than readState.Index' (duration: 15.997262ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:46:08.705343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:08.353746Z","time spent":"351.450743ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:46:08.70541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.297461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-19T02:46:08.705461Z","caller":"traceutil/trace.go:171","msg":"trace[211938667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26523; }","duration":"252.418954ms","start":"2026-10-19T02:46:08.453026Z","end":"2026-10-19T02:46:08.705445Z","steps":["trace[211938667] 'agreement among raft nodes before linearized reading' (duration: 252.177908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:08.705468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.515823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-19T02:46:08.705099Z","caller":"traceutil/trace.go:171","msg":"trace[1243968447] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"250.67891ms","start":"2026-10-19T02:46:08.454343Z","end":"2026-10-19T02:46:08.705021Z","steps":["trace[1243968447] 'process raft request' (duration: 250.215358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:08.70553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.712701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-19T02:46:08.705557Z","caller":"traceutil/trace.go:171","msg":"trace[1053807801] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26523; }","duration":"495.664976ms","start":"2026-10-19T02:46:08.209867Z","end":"2026-10-19T02:46:08.705532Z","steps":["trace[1053807801] 'agreement among raft nodes before linearized reading' (duration: 495.364609ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:08.705581Z","caller":"traceutil/trace.go:171","msg":"trace[1129073244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26523; }","duration":"560.814454ms","start":"2026-10-19T02:46:08.144749Z","end":"2026-10-19T02:46:08.705564Z","steps":["trace[1129073244] 'agreement among raft nodes before linearized reading' (duration: 560.563338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:08.705574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.173427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-19T02:46:08.705606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:08.20984Z","time spent":"495.756979ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-19T02:46:08.70563Z","caller":"traceutil/trace.go:171","msg":"trace[1939774898] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:26523; }","duration":"250.251569ms","start":"2026-10-19T02:46:08.455362Z","end":"2026-10-19T02:46:08.705614Z","steps":["trace[1939774898] 'agreement among raft nodes before linearized reading' (duration: 250.021503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:08.705628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:46:08.144735Z","time spent":"560.883525ms","remote":"127.0.0.1:49694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-19T02:46:08.705594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.79527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:46:08.705723Z","caller":"traceutil/trace.go:171","msg":"trace[327993536] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:26523; }","duration":"189.955074ms","start":"2026-10-19T02:46:08.515736Z","end":"2026-10-19T02:46:08.705691Z","steps":["trace[327993536] 'agreement among raft nodes before linearized reading' (duration: 189.712438ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:08.890986Z","caller":"traceutil/trace.go:171","msg":"trace[1967912669] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"180.247769ms","start":"2026-10-19T02:46:08.71071Z","end":"2026-10-19T02:46:08.890958Z","steps":["trace[1967912669] 'process raft request' (duration: 145.012584ms)","trace[1967912669] 'compare' (duration: 35.117032ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:46:08.892597Z","caller":"traceutil/trace.go:171","msg":"trace[1309357991] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"180.894797ms","start":"2026-10-19T02:46:08.711651Z","end":"2026-10-19T02:46:08.892546Z","steps":["trace[1309357991] 'process raft request' (duration: 180.585179ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:08.892632Z","caller":"traceutil/trace.go:171","msg":"trace[608782651] linearizableReadLoop","detail":"{readStateIndex:27409; appliedIndex:27407; }","duration":"180.447235ms","start":"2026-10-19T02:46:08.712162Z","end":"2026-10-19T02:46:08.89261Z","steps":["trace[608782651] 'read index received' (duration: 143.823395ms)","trace[608782651] 'applied index is now lower than readState.Index' (duration: 36.61945ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:46:08.892858Z","caller":"traceutil/trace.go:171","msg":"trace[1089253144] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"179.607494ms","start":"2026-10-19T02:46:08.713226Z","end":"2026-10-19T02:46:08.892834Z","steps":["trace[1089253144] 'process raft request' (duration: 179.124212ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:08.89289Z","caller":"traceutil/trace.go:171","msg":"trace[1469373274] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"176.705101ms","start":"2026-10-19T02:46:08.716066Z","end":"2026-10-19T02:46:08.892771Z","steps":["trace[1469373274] 'process raft request' (duration: 176.344622ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:08.893039Z","caller":"traceutil/trace.go:171","msg":"trace[1956285367] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"170.573857ms","start":"2026-10-19T02:46:08.722404Z","end":"2026-10-19T02:46:08.892977Z","steps":["trace[1956285367] 'process raft request' (duration: 170.090204ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:08.893044Z","caller":"traceutil/trace.go:171","msg":"trace[1451924917] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"142.312056ms","start":"2026-10-19T02:46:08.7507Z","end":"2026-10-19T02:46:08.893012Z","steps":["trace[1451924917] 'process raft request' (duration: 141.849174ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:46:08.89299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.798084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-19T02:46:08.893263Z","caller":"traceutil/trace.go:171","msg":"trace[998095171] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26529; }","duration":"181.141123ms","start":"2026-10-19T02:46:08.712101Z","end":"2026-10-19T02:46:08.893243Z","steps":["trace[998095171] 'agreement among raft nodes before linearized reading' (duration: 180.61443ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:47:54.565183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24765} {"level":"info","ts":"2026-10-19T02:47:54.746595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24765,"took":"176.28372ms","hash":2601021177,"current-db-size-bytes":59756544,"current-db-size":"60 MB","current-db-size-in-use-bytes":36196352,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-19T02:47:54.746688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2601021177,"revision":24765,"compact-revision":21508} {"level":"info","ts":"2026-10-19T02:48:17.599724Z","caller":"traceutil/trace.go:171","msg":"trace[1378914319] transaction","detail":"{read_only:false; response_revision:27742; number_of_response:1; }","duration":"101.83493ms","start":"2026-10-19T02:48:17.497857Z","end":"2026-10-19T02:48:17.599691Z","steps":["trace[1378914319] 'process raft request' (duration: 96.536037ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:49:50.333361Z","caller":"traceutil/trace.go:171","msg":"trace[1969168212] transaction","detail":"{read_only:false; response_revision:28658; number_of_response:1; }","duration":"109.721977ms","start":"2026-10-19T02:49:50.223587Z","end":"2026-10-19T02:49:50.333309Z","steps":["trace[1969168212] 'process raft request' (duration: 109.394689ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:49:50.469528Z","caller":"traceutil/trace.go:171","msg":"trace[1178551847] transaction","detail":"{read_only:false; response_revision:28660; number_of_response:1; }","duration":"130.443588ms","start":"2026-10-19T02:49:50.339048Z","end":"2026-10-19T02:49:50.469491Z","steps":["trace[1178551847] 'process raft request' (duration: 90.030802ms)","trace[1178551847] 'compare' (duration: 40.287463ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:50:24.794359Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2af340d70e332c3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T02:50:24.801761Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-19T02:50:24.801907Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-19T02:52:54.574303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27534} {"level":"info","ts":"2026-10-19T02:52:54.71449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27534,"took":"134.365327ms","hash":957985387,"current-db-size-bytes":59756544,"current-db-size":"60 MB","current-db-size-in-use-bytes":36904960,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-19T02:52:54.714576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":957985387,"revision":27534,"compact-revision":24765} {"level":"info","ts":"2026-10-19T02:57:54.583852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30170} {"level":"info","ts":"2026-10-19T02:57:54.747473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30170,"took":"158.783979ms","hash":250359982,"current-db-size-bytes":59756544,"current-db-size":"60 MB","current-db-size-in-use-bytes":30347264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-19T02:57:54.74755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":250359982,"revision":30170,"compact-revision":27534} {"level":"info","ts":"2026-10-19T03:02:54.592024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32420} {"level":"info","ts":"2026-10-19T03:02:54.762738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32420,"took":"165.082917ms","hash":3141251922,"current-db-size-bytes":59756544,"current-db-size":"60 MB","current-db-size-in-use-bytes":31191040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-19T03:02:54.762826Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3141251922,"revision":32420,"compact-revision":30170} {"level":"info","ts":"2026-10-19T03:07:54.604339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34715} {"level":"info","ts":"2026-10-19T03:07:54.766003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34715,"took":"155.975668ms","hash":3788756352,"current-db-size-bytes":59756544,"current-db-size":"60 MB","current-db-size-in-use-bytes":31596544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-19T03:07:54.766057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3788756352,"revision":34715,"compact-revision":32420} {"level":"info","ts":"2026-10-19T03:11:43.007897Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2af340d70e332c3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T03:11:43.016074Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-19T03:11:43.016224Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-19T03:12:54.613353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37036} {"level":"info","ts":"2026-10-19T03:12:54.772234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37036,"took":"150.105195ms","hash":3447199686,"current-db-size-bytes":59756544,"current-db-size":"60 MB","current-db-size-in-use-bytes":32829440,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-19T03:12:54.772323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3447199686,"revision":37036,"compact-revision":34715} {"level":"info","ts":"2026-10-19T03:17:54.622328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39309} {"level":"info","ts":"2026-10-19T03:17:54.767181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39309,"took":"141.089599ms","hash":2613826218,"current-db-size-bytes":60608512,"current-db-size":"61 MB","current-db-size-in-use-bytes":31551488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-19T03:17:54.767307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2613826218,"revision":39309,"compact-revision":37036}