{"level":"warn","ts":"2026-07-27T20:08:03.833106Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-27T20:08:03.833182Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.186:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.186:2380","--initial-cluster=instance=https://199.19.213.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.186: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-07-27T20:08:03.833245Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-27T20:08:03.833257Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-07-27T20:08:03.833273Z","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-07-27T20:08:03.833885Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"]} {"level":"info","ts":"2026-07-27T20:08:03.833954Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.186: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-07-27T20:08:03.838965Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.779517ms"} {"level":"info","ts":"2026-07-27T20:08:03.851357Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"22478416d949d47d","cluster-id":"568bb29601528c07"} {"level":"info","ts":"2026-07-27T20:08:03.851545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=()"} {"level":"info","ts":"2026-07-27T20:08:03.851587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 0"} {"level":"info","ts":"2026-07-27T20:08:03.851629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 22478416d949d47d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-27T20:08:03.851643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 1"} {"level":"info","ts":"2026-07-27T20:08:03.851725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"warn","ts":"2026-07-27T20:08:03.857131Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-27T20:08:03.858749Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-27T20:08:03.860193Z","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-07-27T20:08:03.863341Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"22478416d949d47d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-27T20:08:03.863509Z","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-07-27T20:08:03.863534Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"22478416d949d47d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-27T20:08:03.863612Z","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-07-27T20:08:03.863661Z","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-07-27T20:08:03.864847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"info","ts":"2026-07-27T20:08:03.866464Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","added-peer-id":"22478416d949d47d","added-peer-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-07-27T20:08:03.871362Z","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-07-27T20:08:03.871513Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-07-27T20:08:03.871568Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-07-27T20:08:03.871735Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"22478416d949d47d","initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-27T20:08:03.871803Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-27T20:08:04.752574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d is starting a new election at term 1"} {"level":"info","ts":"2026-07-27T20:08:04.752645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-27T20:08:04.752681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgPreVoteResp from 22478416d949d47d at term 1"} {"level":"info","ts":"2026-07-27T20:08:04.752702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became candidate at term 2"} {"level":"info","ts":"2026-07-27T20:08:04.752712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgVoteResp from 22478416d949d47d at term 2"} {"level":"info","ts":"2026-07-27T20:08:04.752734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became leader at term 2"} {"level":"info","ts":"2026-07-27T20:08:04.752749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 22478416d949d47d elected leader 22478416d949d47d at term 2"} {"level":"info","ts":"2026-07-27T20:08:04.75392Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"22478416d949d47d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.186:2379]}","request-path":"/0/members/22478416d949d47d/attributes","cluster-id":"568bb29601528c07","publish-timeout":"7s"} {"level":"info","ts":"2026-07-27T20:08:04.754094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:08:04.754154Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:08:04.754221Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:08:04.75429Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-27T20:08:04.754376Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-27T20:08:04.75544Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:08:04.755556Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:08:04.755594Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:08:04.755922Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-27T20:08:04.75779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.186:2379"} {"level":"warn","ts":"2026-07-27T20:08:12.550321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.333269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:08:12.550585Z","caller":"traceutil/trace.go:171","msg":"trace[1678034802] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:329; }","duration":"101.588425ms","start":"2026-07-27T20:08:12.448952Z","end":"2026-07-27T20:08:12.550541Z","steps":["trace[1678034802] 'range keys from in-memory index tree' (duration: 101.234878ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:18.3411Z","caller":"traceutil/trace.go:171","msg":"trace[527240859] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"428.978933ms","start":"2026-07-27T20:08:17.912093Z","end":"2026-07-27T20:08:18.341072Z","steps":["trace[527240859] 'read index received' (duration: 393.441138ms)","trace[527240859] 'applied index is now lower than readState.Index' (duration: 35.536725ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:08:18.341424Z","caller":"traceutil/trace.go:171","msg":"trace[1390486221] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"477.701566ms","start":"2026-07-27T20:08:17.863705Z","end":"2026-07-27T20:08:18.341406Z","steps":["trace[1390486221] 'process raft request' (duration: 441.868234ms)","trace[1390486221] 'compare' (duration: 35.404312ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:08:18.341851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.037745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-27T20:08:18.341939Z","caller":"traceutil/trace.go:171","msg":"trace[2021197199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:402; }","duration":"265.179227ms","start":"2026-07-27T20:08:18.07674Z","end":"2026-07-27T20:08:18.341919Z","steps":["trace[2021197199] 'agreement among raft nodes before linearized reading' (duration: 265.008483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:18.341969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:08:17.863689Z","time spent":"477.772697ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:08:18.342242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.144288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.186\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-27T20:08:18.34229Z","caller":"traceutil/trace.go:171","msg":"trace[1963764783] range","detail":"{range_begin:/registry/masterleases/199.19.213.186; range_end:; response_count:1; response_revision:402; }","duration":"430.224089ms","start":"2026-07-27T20:08:17.912053Z","end":"2026-07-27T20:08:18.342278Z","steps":["trace[1963764783] 'agreement among raft nodes before linearized reading' (duration: 430.152227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:18.342328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:08:17.912038Z","time spent":"430.281461ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.186\" "} {"level":"warn","ts":"2026-07-27T20:08:18.703826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.389254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:08:18.704109Z","caller":"traceutil/trace.go:171","msg":"trace[574345191] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:416; }","duration":"123.730671ms","start":"2026-07-27T20:08:18.580342Z","end":"2026-07-27T20:08:18.704073Z","steps":["trace[574345191] 'read index received' (duration: 35.321µs)","trace[574345191] 'applied index is now lower than readState.Index' (duration: 123.69416ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:08:18.704184Z","caller":"traceutil/trace.go:171","msg":"trace[1616161191] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"357.197349ms","start":"2026-07-27T20:08:18.34696Z","end":"2026-07-27T20:08:18.704157Z","steps":["trace[1616161191] 'process raft request' (duration: 147.225803ms)","trace[1616161191] 'compare' (duration: 209.20713ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:08:18.704257Z","caller":"traceutil/trace.go:171","msg":"trace[1064354982] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"326.692306ms","start":"2026-07-27T20:08:18.377552Z","end":"2026-07-27T20:08:18.704245Z","steps":["trace[1064354982] 'process raft request' (duration: 326.389079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:18.704324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:08:18.346943Z","time spent":"357.304702ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:08:18.704338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:08:18.37753Z","time spent":"326.769137ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:08:18.704402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.851532ms","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-07-27T20:08:18.704486Z","caller":"traceutil/trace.go:171","msg":"trace[2023003426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:404; }","duration":"109.965594ms","start":"2026-07-27T20:08:18.5945Z","end":"2026-07-27T20:08:18.704466Z","steps":["trace[2023003426] 'agreement among raft nodes before linearized reading' (duration: 109.78542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:18.704647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.249071ms","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-07-27T20:08:18.704796Z","caller":"traceutil/trace.go:171","msg":"trace[752505135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:404; }","duration":"124.463205ms","start":"2026-07-27T20:08:18.580301Z","end":"2026-07-27T20:08:18.704764Z","steps":["trace[752505135] 'agreement among raft nodes before linearized reading' (duration: 124.119479ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:18.863067Z","caller":"traceutil/trace.go:171","msg":"trace[330351262] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"153.788125ms","start":"2026-07-27T20:08:18.709253Z","end":"2026-07-27T20:08:18.863041Z","steps":["trace[330351262] 'process raft request' (duration: 153.731424ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:18.863088Z","caller":"traceutil/trace.go:171","msg":"trace[1161907517] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"154.5224ms","start":"2026-07-27T20:08:18.708512Z","end":"2026-07-27T20:08:18.863034Z","steps":["trace[1161907517] 'process raft request' (duration: 137.143891ms)","trace[1161907517] 'compare' (duration: 17.146355ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:08:18.863104Z","caller":"traceutil/trace.go:171","msg":"trace[100736081] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"154.445379ms","start":"2026-07-27T20:08:18.708621Z","end":"2026-07-27T20:08:18.863067Z","steps":["trace[100736081] 'read index received' (duration: 137.135671ms)","trace[100736081] 'applied index is now lower than readState.Index' (duration: 17.305418ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:08:18.863258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.620832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-27T20:08:18.863325Z","caller":"traceutil/trace.go:171","msg":"trace[765742770] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:406; }","duration":"154.725894ms","start":"2026-07-27T20:08:18.708587Z","end":"2026-07-27T20:08:18.863313Z","steps":["trace[765742770] 'agreement among raft nodes before linearized reading' (duration: 154.587611ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:42.869249Z","caller":"traceutil/trace.go:171","msg":"trace[1965228787] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"106.026347ms","start":"2026-07-27T20:08:42.763158Z","end":"2026-07-27T20:08:42.869184Z","steps":["trace[1965228787] 'process raft request' (duration: 105.680069ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:43.024899Z","caller":"traceutil/trace.go:171","msg":"trace[1517682427] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"151.978492ms","start":"2026-07-27T20:08:42.872897Z","end":"2026-07-27T20:08:43.024876Z","steps":["trace[1517682427] 'process raft request' (duration: 66.49511ms)","trace[1517682427] 'compare' (duration: 85.287898ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:08:43.026354Z","caller":"traceutil/trace.go:171","msg":"trace[1157955307] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"152.793588ms","start":"2026-07-27T20:08:42.873537Z","end":"2026-07-27T20:08:43.02633Z","steps":["trace[1157955307] 'process raft request' (duration: 152.684896ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:43.026368Z","caller":"traceutil/trace.go:171","msg":"trace[1497519593] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"153.413931ms","start":"2026-07-27T20:08:42.872925Z","end":"2026-07-27T20:08:43.026339Z","steps":["trace[1497519593] 'process raft request' (duration: 153.186417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:46.973717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.491814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:08:46.973913Z","caller":"traceutil/trace.go:171","msg":"trace[1437555074] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"124.969528ms","start":"2026-07-27T20:08:46.848916Z","end":"2026-07-27T20:08:46.973886Z","steps":["trace[1437555074] 'read index received' (duration: 52.181µs)","trace[1437555074] 'applied index is now lower than readState.Index' (duration: 124.915287ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:08:46.973934Z","caller":"traceutil/trace.go:171","msg":"trace[647192024] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"389.835262ms","start":"2026-07-27T20:08:46.58406Z","end":"2026-07-27T20:08:46.973895Z","steps":["trace[647192024] 'process raft request' (duration: 225.026743ms)","trace[647192024] 'compare' (duration: 164.205677ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:08:46.974098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:08:46.584034Z","time spent":"389.985345ms","remote":"127.0.0.1:59336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:08:46.974178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.254044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lf4wt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-27T20:08:46.974226Z","caller":"traceutil/trace.go:171","msg":"trace[554350275] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lf4wt; range_end:; response_count:1; response_revision:627; }","duration":"125.354626ms","start":"2026-07-27T20:08:46.848859Z","end":"2026-07-27T20:08:46.974213Z","steps":["trace[554350275] 'agreement among raft nodes before linearized reading' (duration: 125.09378ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:09:11.365404Z","caller":"traceutil/trace.go:171","msg":"trace[881896005] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:847; }","duration":"207.600402ms","start":"2026-07-27T20:09:11.157777Z","end":"2026-07-27T20:09:11.365377Z","steps":["trace[881896005] 'read index received' (duration: 51.63863ms)","trace[881896005] 'applied index is now lower than readState.Index' (duration: 155.960692ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:09:11.365527Z","caller":"traceutil/trace.go:171","msg":"trace[1596133757] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"208.422408ms","start":"2026-07-27T20:09:11.157092Z","end":"2026-07-27T20:09:11.365515Z","steps":["trace[1596133757] 'process raft request' (duration: 146.500581ms)","trace[1596133757] 'compare' (duration: 61.628922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:09:11.365728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.938748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-27T20:09:11.365807Z","caller":"traceutil/trace.go:171","msg":"trace[2066724976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:818; }","duration":"208.02523ms","start":"2026-07-27T20:09:11.157763Z","end":"2026-07-27T20:09:11.365788Z","steps":["trace[2066724976] 'agreement among raft nodes before linearized reading' (duration: 207.775675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:09:11.386399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.155588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:09:11.386501Z","caller":"traceutil/trace.go:171","msg":"trace[1547195570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"169.301201ms","start":"2026-07-27T20:09:11.217178Z","end":"2026-07-27T20:09:11.386479Z","steps":["trace[1547195570] 'agreement among raft nodes before linearized reading' (duration: 169.128577ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:09:38.070703Z","caller":"traceutil/trace.go:171","msg":"trace[734881617] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"104.673293ms","start":"2026-07-27T20:09:37.965993Z","end":"2026-07-27T20:09:38.070666Z","steps":["trace[734881617] 'process raft request' (duration: 104.424667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:22.551099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.322935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-27T20:10:22.551199Z","caller":"traceutil/trace.go:171","msg":"trace[1681457382] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1861; }","duration":"172.443157ms","start":"2026-07-27T20:10:22.378735Z","end":"2026-07-27T20:10:22.551179Z","steps":["trace[1681457382] 'range keys from in-memory index tree' (duration: 172.006789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:22.55145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.185776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9f42627-2d71-464f-a581-0408fbc58617\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-27T20:10:22.551613Z","caller":"traceutil/trace.go:171","msg":"trace[79258155] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9f42627-2d71-464f-a581-0408fbc58617; range_end:; response_count:1; response_revision:1861; }","duration":"221.383211ms","start":"2026-07-27T20:10:22.330195Z","end":"2026-07-27T20:10:22.551579Z","steps":["trace[79258155] 'range keys from in-memory index tree' (duration: 220.93317ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:22.940847Z","caller":"traceutil/trace.go:171","msg":"trace[1760910130] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"212.781366ms","start":"2026-07-27T20:10:22.728039Z","end":"2026-07-27T20:10:22.940821Z","steps":["trace[1760910130] 'process raft request' (duration: 211.260984ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:22.944117Z","caller":"traceutil/trace.go:171","msg":"trace[1126640900] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"215.300167ms","start":"2026-07-27T20:10:22.728781Z","end":"2026-07-27T20:10:22.944082Z","steps":["trace[1126640900] 'process raft request' (duration: 214.991181ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:22.944197Z","caller":"traceutil/trace.go:171","msg":"trace[1474355726] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"215.232676ms","start":"2026-07-27T20:10:22.728896Z","end":"2026-07-27T20:10:22.944128Z","steps":["trace[1474355726] 'process raft request' (duration: 215.086143ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:22.94432Z","caller":"traceutil/trace.go:171","msg":"trace[1236351236] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"215.213705ms","start":"2026-07-27T20:10:22.729075Z","end":"2026-07-27T20:10:22.944288Z","steps":["trace[1236351236] 'process raft request' (duration: 214.9935ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:35.488883Z","caller":"traceutil/trace.go:171","msg":"trace[302235793] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"116.976063ms","start":"2026-07-27T20:10:35.371877Z","end":"2026-07-27T20:10:35.488853Z","steps":["trace[302235793] 'process raft request' (duration: 116.783128ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:11.886528Z","caller":"traceutil/trace.go:171","msg":"trace[801981038] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"142.904829ms","start":"2026-07-27T20:11:11.743579Z","end":"2026-07-27T20:11:11.886484Z","steps":["trace[801981038] 'process raft request' (duration: 121.435451ms)","trace[801981038] 'compare' (duration: 21.268764ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:11:18.010156Z","caller":"traceutil/trace.go:171","msg":"trace[880503821] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"105.171817ms","start":"2026-07-27T20:11:17.904923Z","end":"2026-07-27T20:11:18.010095Z","steps":["trace[880503821] 'process raft request' (duration: 104.920382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:18.362727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.454184ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-27T20:11:18.362902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:18.010944Z","time spent":"351.909594ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-27T20:11:18.362931Z","caller":"traceutil/trace.go:171","msg":"trace[1364543792] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2298; }","duration":"351.459976ms","start":"2026-07-27T20:11:18.011437Z","end":"2026-07-27T20:11:18.362896Z","steps":["trace[1364543792] 'read index received' (duration: 123.832798ms)","trace[1364543792] 'applied index is now lower than readState.Index' (duration: 227.622848ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:11:18.363039Z","caller":"traceutil/trace.go:171","msg":"trace[2004006501] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"348.385824ms","start":"2026-07-27T20:11:18.014627Z","end":"2026-07-27T20:11:18.363013Z","steps":["trace[2004006501] 'process raft request' (duration: 348.219481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:18.363167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.705691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:11:18.363188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:18.014608Z","time spent":"348.501147ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:11:18.363229Z","caller":"traceutil/trace.go:171","msg":"trace[2085212150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2213; }","duration":"351.797802ms","start":"2026-07-27T20:11:18.011417Z","end":"2026-07-27T20:11:18.363215Z","steps":["trace[2085212150] 'agreement among raft nodes before linearized reading' (duration: 351.616779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:18.36328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:18.011406Z","time spent":"351.862893ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-27T20:11:18.450039Z","caller":"traceutil/trace.go:171","msg":"trace[1872062040] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"124.778137ms","start":"2026-07-27T20:11:18.32522Z","end":"2026-07-27T20:11:18.449998Z","steps":["trace[1872062040] 'process raft request' (duration: 124.585503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:18.450308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.524428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-27T20:11:18.450392Z","caller":"traceutil/trace.go:171","msg":"trace[1573200499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2214; }","duration":"338.638089ms","start":"2026-07-27T20:11:18.111732Z","end":"2026-07-27T20:11:18.45037Z","steps":["trace[1573200499] 'agreement among raft nodes before linearized reading' (duration: 338.329533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:18.450356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.412104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-27T20:11:18.450438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:18.11169Z","time spent":"338.740801ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-27T20:11:18.450456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.736001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-27T20:11:18.450527Z","caller":"traceutil/trace.go:171","msg":"trace[1027728667] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2214; }","duration":"364.597537ms","start":"2026-07-27T20:11:18.085865Z","end":"2026-07-27T20:11:18.450463Z","steps":["trace[1027728667] 'agreement among raft nodes before linearized reading' (duration: 364.359823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:18.450628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:18.085853Z","time spent":"364.750022ms","remote":"127.0.0.1:45738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:11:18.450664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.698046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-27T20:11:18.450533Z","caller":"traceutil/trace.go:171","msg":"trace[122088989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2214; }","duration":"340.845635ms","start":"2026-07-27T20:11:18.10967Z","end":"2026-07-27T20:11:18.450515Z","steps":["trace[122088989] 'agreement among raft nodes before linearized reading' (duration: 340.62801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:18.450917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:18.109658Z","time spent":"341.178131ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-27T20:11:18.450927Z","caller":"traceutil/trace.go:171","msg":"trace[177875663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2214; }","duration":"395.044124ms","start":"2026-07-27T20:11:18.05569Z","end":"2026-07-27T20:11:18.450734Z","steps":["trace[177875663] 'agreement among raft nodes before linearized reading' (duration: 394.568714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:18.451197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:18.055671Z","time spent":"395.510333ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-27T20:12:26.383627Z","caller":"traceutil/trace.go:171","msg":"trace[1125479972] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"135.043763ms","start":"2026-07-27T20:12:26.248547Z","end":"2026-07-27T20:12:26.383591Z","steps":["trace[1125479972] 'process raft request' (duration: 134.822248ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:26.871034Z","caller":"traceutil/trace.go:171","msg":"trace[1893527845] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2890; }","duration":"102.280184ms","start":"2026-07-27T20:12:26.768728Z","end":"2026-07-27T20:12:26.871008Z","steps":["trace[1893527845] 'read index received' (duration: 96.350305ms)","trace[1893527845] 'applied index is now lower than readState.Index' (duration: 5.928489ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:26.871103Z","caller":"traceutil/trace.go:171","msg":"trace[270650841] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"118.342198ms","start":"2026-07-27T20:12:26.752725Z","end":"2026-07-27T20:12:26.871067Z","steps":["trace[270650841] 'process raft request' (duration: 112.301176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:26.871483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.748383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-27T20:12:26.871541Z","caller":"traceutil/trace.go:171","msg":"trace[1815725357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2782; }","duration":"102.850106ms","start":"2026-07-27T20:12:26.768677Z","end":"2026-07-27T20:12:26.871527Z","steps":["trace[1815725357] 'agreement among raft nodes before linearized reading' (duration: 102.719604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:17.161122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.800367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:16:17.161231Z","caller":"traceutil/trace.go:171","msg":"trace[1490188672] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5103; }","duration":"194.233674ms","start":"2026-07-27T20:16:16.966983Z","end":"2026-07-27T20:16:17.161217Z","steps":["trace[1490188672] 'read index received' (duration: 53.148132ms)","trace[1490188672] 'applied index is now lower than readState.Index' (duration: 141.084452ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:16:17.161251Z","caller":"traceutil/trace.go:171","msg":"trace[842739380] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"218.314006ms","start":"2026-07-27T20:16:16.94291Z","end":"2026-07-27T20:16:17.161224Z","steps":["trace[842739380] 'process raft request' (duration: 77.347886ms)","trace[842739380] 'compare' (duration: 140.665834ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:16:17.161327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.348906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:16:17.161344Z","caller":"traceutil/trace.go:171","msg":"trace[597228990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4918; }","duration":"194.422487ms","start":"2026-07-27T20:16:16.966915Z","end":"2026-07-27T20:16:17.161338Z","steps":["trace[597228990] 'agreement among raft nodes before linearized reading' (duration: 194.358926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:17.176661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.109122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-07-27T20:16:17.176722Z","caller":"traceutil/trace.go:171","msg":"trace[436155715] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4919; }","duration":"142.198903ms","start":"2026-07-27T20:16:17.03451Z","end":"2026-07-27T20:16:17.176709Z","steps":["trace[436155715] 'agreement among raft nodes before linearized reading' (duration: 142.02584ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:16:29.717969Z","caller":"traceutil/trace.go:171","msg":"trace[2048808089] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"109.857731ms","start":"2026-07-27T20:16:29.608079Z","end":"2026-07-27T20:16:29.717937Z","steps":["trace[2048808089] 'process raft request' (duration: 31.091907ms)","trace[2048808089] 'compare' (duration: 78.5918ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:16:37.672396Z","caller":"traceutil/trace.go:171","msg":"trace[688572613] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"131.934935ms","start":"2026-07-27T20:16:37.54043Z","end":"2026-07-27T20:16:37.672365Z","steps":["trace[688572613] 'process raft request' (duration: 131.759982ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:16:47.774823Z","caller":"traceutil/trace.go:171","msg":"trace[534950722] linearizableReadLoop","detail":"{readStateIndex:5705; appliedIndex:5704; }","duration":"128.967559ms","start":"2026-07-27T20:16:47.645825Z","end":"2026-07-27T20:16:47.774793Z","steps":["trace[534950722] 'read index received' (duration: 113.238986ms)","trace[534950722] 'applied index is now lower than readState.Index' (duration: 15.727333ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:16:47.775005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.157393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:16:47.775098Z","caller":"traceutil/trace.go:171","msg":"trace[1566042981] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5503; }","duration":"129.294836ms","start":"2026-07-27T20:16:47.645782Z","end":"2026-07-27T20:16:47.775077Z","steps":["trace[1566042981] 'agreement among raft nodes before linearized reading' (duration: 129.108822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:47.775163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.395439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-27T20:16:47.775233Z","caller":"traceutil/trace.go:171","msg":"trace[1116415277] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5503; }","duration":"114.518551ms","start":"2026-07-27T20:16:47.660695Z","end":"2026-07-27T20:16:47.775214Z","steps":["trace[1116415277] 'agreement among raft nodes before linearized reading' (duration: 114.295977ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:16:47.77489Z","caller":"traceutil/trace.go:171","msg":"trace[1634476299] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"137.025704ms","start":"2026-07-27T20:16:47.637787Z","end":"2026-07-27T20:16:47.774813Z","steps":["trace[1634476299] 'process raft request' (duration: 121.288481ms)","trace[1634476299] 'compare' (duration: 15.57707ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:16:48.054004Z","caller":"traceutil/trace.go:171","msg":"trace[1791538544] linearizableReadLoop","detail":"{readStateIndex:5708; appliedIndex:5707; }","duration":"108.914774ms","start":"2026-07-27T20:16:47.94506Z","end":"2026-07-27T20:16:48.053975Z","steps":["trace[1791538544] 'read index received' (duration: 84.007065ms)","trace[1791538544] 'applied index is now lower than readState.Index' (duration: 24.906729ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:16:48.054149Z","caller":"traceutil/trace.go:171","msg":"trace[341095735] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"112.712646ms","start":"2026-07-27T20:16:47.941424Z","end":"2026-07-27T20:16:48.054137Z","steps":["trace[341095735] 'process raft request' (duration: 87.714065ms)","trace[341095735] 'compare' (duration: 24.674985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:16:48.054794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.70215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81823"} {"level":"info","ts":"2026-07-27T20:16:48.054931Z","caller":"traceutil/trace.go:171","msg":"trace[1316459048] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5505; }","duration":"109.873022ms","start":"2026-07-27T20:16:47.945033Z","end":"2026-07-27T20:16:48.054907Z","steps":["trace[1316459048] 'agreement among raft nodes before linearized reading' (duration: 109.465684ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:18:04.907175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-07-27T20:18:04.977391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"68.810132ms","hash":2629026375,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":34476032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-27T20:18:04.977444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2629026375,"revision":2991,"compact-revision":-1} {"level":"info","ts":"2026-07-27T20:22:33.228376Z","caller":"traceutil/trace.go:171","msg":"trace[732594069] transaction","detail":"{read_only:false; response_revision:8127; number_of_response:1; }","duration":"142.098584ms","start":"2026-07-27T20:22:33.086253Z","end":"2026-07-27T20:22:33.228351Z","steps":["trace[732594069] 'process raft request' (duration: 141.932559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:45.578547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.681257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:22:45.578747Z","caller":"traceutil/trace.go:171","msg":"trace[1539451437] linearizableReadLoop","detail":"{readStateIndex:8478; appliedIndex:8477; }","duration":"175.024834ms","start":"2026-07-27T20:22:45.403704Z","end":"2026-07-27T20:22:45.578729Z","steps":["trace[1539451437] 'read index received' (duration: 36.481µs)","trace[1539451437] 'applied index is now lower than readState.Index' (duration: 174.986533ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:22:45.578806Z","caller":"traceutil/trace.go:171","msg":"trace[1640259505] transaction","detail":"{read_only:false; response_revision:8188; number_of_response:1; }","duration":"299.357175ms","start":"2026-07-27T20:22:45.279419Z","end":"2026-07-27T20:22:45.578776Z","steps":["trace[1640259505] 'process raft request' (duration: 29.359252ms)","trace[1640259505] 'compare' (duration: 269.509934ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:22:45.578902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.146657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:45.579007Z","caller":"traceutil/trace.go:171","msg":"trace[1519181195] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8188; }","duration":"175.343971ms","start":"2026-07-27T20:22:45.403643Z","end":"2026-07-27T20:22:45.578987Z","steps":["trace[1519181195] 'agreement among raft nodes before linearized reading' (duration: 175.140526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.397432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.231269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:22:46.397554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.29434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:22:46.397591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.559715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:22:46.397675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.340432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.397647Z","caller":"traceutil/trace.go:171","msg":"trace[581817852] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8193; }","duration":"233.444423ms","start":"2026-07-27T20:22:46.164148Z","end":"2026-07-27T20:22:46.397593Z","steps":["trace[581817852] 'count revisions from in-memory index tree' (duration: 233.180447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.397742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.654599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.397772Z","caller":"traceutil/trace.go:171","msg":"trace[1037304877] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8193; }","duration":"148.706369ms","start":"2026-07-27T20:22:46.249058Z","end":"2026-07-27T20:22:46.397764Z","steps":["trace[1037304877] 'range keys from in-memory index tree' (duration: 148.580237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.397826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.138681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:22:46.397788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.775102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.397884Z","caller":"traceutil/trace.go:171","msg":"trace[1118764859] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8193; }","duration":"217.210502ms","start":"2026-07-27T20:22:46.180656Z","end":"2026-07-27T20:22:46.397867Z","steps":["trace[1118764859] 'range keys from in-memory index tree' (duration: 217.0647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.397857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.905954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.39791Z","caller":"traceutil/trace.go:171","msg":"trace[1700002848] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8193; }","duration":"182.964517ms","start":"2026-07-27T20:22:46.214923Z","end":"2026-07-27T20:22:46.397887Z","steps":["trace[1700002848] 'range keys from in-memory index tree' (duration: 182.639569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.397936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.896002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.397953Z","caller":"traceutil/trace.go:171","msg":"trace[1912053681] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8193; }","duration":"184.041057ms","start":"2026-07-27T20:22:46.21389Z","end":"2026-07-27T20:22:46.397931Z","steps":["trace[1912053681] 'count revisions from in-memory index tree' (duration: 183.844653ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.397977Z","caller":"traceutil/trace.go:171","msg":"trace[1924476229] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8193; }","duration":"161.964594ms","start":"2026-07-27T20:22:46.236002Z","end":"2026-07-27T20:22:46.397966Z","steps":["trace[1924476229] 'count revisions from in-memory index tree' (duration: 161.848052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.398058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.262304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:22:46.398145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.747774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.398151Z","caller":"traceutil/trace.go:171","msg":"trace[522970355] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8193; }","duration":"217.380676ms","start":"2026-07-27T20:22:46.180746Z","end":"2026-07-27T20:22:46.398127Z","steps":["trace[522970355] 'count revisions from in-memory index tree' (duration: 217.180593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.398165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.989382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.398185Z","caller":"traceutil/trace.go:171","msg":"trace[1165349219] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8193; }","duration":"216.803815ms","start":"2026-07-27T20:22:46.181371Z","end":"2026-07-27T20:22:46.398175Z","steps":["trace[1165349219] 'range keys from in-memory index tree' (duration: 216.701313ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.398206Z","caller":"traceutil/trace.go:171","msg":"trace[1516722601] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8193; }","duration":"201.039793ms","start":"2026-07-27T20:22:46.197151Z","end":"2026-07-27T20:22:46.398191Z","steps":["trace[1516722601] 'range keys from in-memory index tree' (duration: 200.956921ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.397704Z","caller":"traceutil/trace.go:171","msg":"trace[1948631698] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8193; }","duration":"149.369232ms","start":"2026-07-27T20:22:46.248326Z","end":"2026-07-27T20:22:46.397695Z","steps":["trace[1948631698] 'count revisions from in-memory index tree' (duration: 149.2934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.397594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.794481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:22:46.398291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.676926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.398302Z","caller":"traceutil/trace.go:171","msg":"trace[1411287278] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8193; }","duration":"233.523905ms","start":"2026-07-27T20:22:46.164767Z","end":"2026-07-27T20:22:46.398291Z","steps":["trace[1411287278] 'range keys from in-memory index tree' (duration: 232.716949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.3975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.950334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.398329Z","caller":"traceutil/trace.go:171","msg":"trace[1247063905] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8193; }","duration":"200.731618ms","start":"2026-07-27T20:22:46.197586Z","end":"2026-07-27T20:22:46.398317Z","steps":["trace[1247063905] 'range keys from in-memory index tree' (duration: 200.636466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.397494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.592498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.397631Z","caller":"traceutil/trace.go:171","msg":"trace[1152807429] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8193; }","duration":"149.383672ms","start":"2026-07-27T20:22:46.24823Z","end":"2026-07-27T20:22:46.397613Z","steps":["trace[1152807429] 'range keys from in-memory index tree' (duration: 149.208158ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.398389Z","caller":"traceutil/trace.go:171","msg":"trace[475751152] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8193; }","duration":"184.526067ms","start":"2026-07-27T20:22:46.213853Z","end":"2026-07-27T20:22:46.398379Z","steps":["trace[475751152] 'range keys from in-memory index tree' (duration: 183.546247ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.397699Z","caller":"traceutil/trace.go:171","msg":"trace[743329049] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8193; }","duration":"161.719849ms","start":"2026-07-27T20:22:46.235958Z","end":"2026-07-27T20:22:46.397678Z","steps":["trace[743329049] 'range keys from in-memory index tree' (duration: 161.405062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.39851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.244367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:22:46.398215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.398577Z","caller":"traceutil/trace.go:171","msg":"trace[720645830] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8193; }","duration":"254.011767ms","start":"2026-07-27T20:22:46.144553Z","end":"2026-07-27T20:22:46.398565Z","steps":["trace[720645830] 'range keys from in-memory index tree' (duration: 253.585229ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.398607Z","caller":"traceutil/trace.go:171","msg":"trace[1469597950] linearizableReadLoop","detail":"{readStateIndex:8484; appliedIndex:8483; }","duration":"127.907881ms","start":"2026-07-27T20:22:46.270689Z","end":"2026-07-27T20:22:46.398597Z","steps":["trace[1469597950] 'read index received' (duration: 113.384633ms)","trace[1469597950] 'applied index is now lower than readState.Index' (duration: 14.521628ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:22:46.398695Z","caller":"traceutil/trace.go:171","msg":"trace[1196934038] transaction","detail":"{read_only:false; response_revision:8194; number_of_response:1; }","duration":"142.47404ms","start":"2026-07-27T20:22:46.25621Z","end":"2026-07-27T20:22:46.398684Z","steps":["trace[1196934038] 'process raft request' (duration: 127.859801ms)","trace[1196934038] 'compare' (duration: 13.336635ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:22:46.397562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.287049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.398764Z","caller":"traceutil/trace.go:171","msg":"trace[1313865680] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8193; }","duration":"234.518294ms","start":"2026-07-27T20:22:46.164234Z","end":"2026-07-27T20:22:46.398753Z","steps":["trace[1313865680] 'range keys from in-memory index tree' (duration: 233.220138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.398774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.021263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.398813Z","caller":"traceutil/trace.go:171","msg":"trace[500127339] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8194; }","duration":"128.081824ms","start":"2026-07-27T20:22:46.270721Z","end":"2026-07-27T20:22:46.398802Z","steps":["trace[500127339] 'agreement among raft nodes before linearized reading' (duration: 127.988472ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.398541Z","caller":"traceutil/trace.go:171","msg":"trace[1192942272] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8193; }","duration":"201.286518ms","start":"2026-07-27T20:22:46.197245Z","end":"2026-07-27T20:22:46.398531Z","steps":["trace[1192942272] 'count revisions from in-memory index tree' (duration: 201.198616ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.39835Z","caller":"traceutil/trace.go:171","msg":"trace[2058066644] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8193; }","duration":"161.826281ms","start":"2026-07-27T20:22:46.236511Z","end":"2026-07-27T20:22:46.398338Z","steps":["trace[2058066644] 'range keys from in-memory index tree' (duration: 160.904693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.399304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.816056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:22:46.399345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.51924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:22:46.399388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.597726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.399425Z","caller":"traceutil/trace.go:171","msg":"trace[770543177] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8194; }","duration":"108.624833ms","start":"2026-07-27T20:22:46.290779Z","end":"2026-07-27T20:22:46.399404Z","steps":["trace[770543177] 'agreement among raft nodes before linearized reading' (duration: 108.49298ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.39946Z","caller":"traceutil/trace.go:171","msg":"trace[1916492722] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8194; }","duration":"127.673807ms","start":"2026-07-27T20:22:46.271764Z","end":"2026-07-27T20:22:46.399438Z","steps":["trace[1916492722] 'agreement among raft nodes before linearized reading' (duration: 127.553315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.39959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.88445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.399668Z","caller":"traceutil/trace.go:171","msg":"trace[415744775] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8194; }","duration":"128.970621ms","start":"2026-07-27T20:22:46.270673Z","end":"2026-07-27T20:22:46.399644Z","steps":["trace[415744775] 'agreement among raft nodes before linearized reading' (duration: 128.867189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:22:46.399763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.898357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:22:46.399825Z","caller":"traceutil/trace.go:171","msg":"trace[1992118112] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8194; }","duration":"108.983739ms","start":"2026-07-27T20:22:46.290825Z","end":"2026-07-27T20:22:46.399809Z","steps":["trace[1992118112] 'agreement among raft nodes before linearized reading' (duration: 108.890587ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:22:46.399377Z","caller":"traceutil/trace.go:171","msg":"trace[1007750430] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8194; }","duration":"107.92461ms","start":"2026-07-27T20:22:46.291429Z","end":"2026-07-27T20:22:46.399353Z","steps":["trace[1007750430] 'agreement among raft nodes before linearized reading' (duration: 107.794816ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:04.913161Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6128} {"level":"info","ts":"2026-07-27T20:23:05.019156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6128,"took":"104.174836ms","hash":2360541721,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":23097344,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-27T20:23:05.019229Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2360541721,"revision":6128,"compact-revision":2991} {"level":"info","ts":"2026-07-27T20:23:14.319029Z","caller":"traceutil/trace.go:171","msg":"trace[1671573434] linearizableReadLoop","detail":"{readStateIndex:8695; appliedIndex:8694; }","duration":"154.555621ms","start":"2026-07-27T20:23:14.164448Z","end":"2026-07-27T20:23:14.319004Z","steps":["trace[1671573434] 'read index received' (duration: 149.287471ms)","trace[1671573434] 'applied index is now lower than readState.Index' (duration: 5.26709ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:23:14.319172Z","caller":"traceutil/trace.go:171","msg":"trace[258769758] transaction","detail":"{read_only:false; response_revision:8396; number_of_response:1; }","duration":"183.147119ms","start":"2026-07-27T20:23:14.135992Z","end":"2026-07-27T20:23:14.319139Z","steps":["trace[258769758] 'process raft request' (duration: 182.738891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:14.319492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.423236ms","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-07-27T20:23:14.319539Z","caller":"traceutil/trace.go:171","msg":"trace[16800820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8396; }","duration":"146.516878ms","start":"2026-07-27T20:23:14.17301Z","end":"2026-07-27T20:23:14.319526Z","steps":["trace[16800820] 'agreement among raft nodes before linearized reading' (duration: 146.346095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:14.31958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.107763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-27T20:23:14.319723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.721374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wsm6v\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-27T20:23:14.319764Z","caller":"traceutil/trace.go:171","msg":"trace[1638641098] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wsm6v; range_end:; response_count:1; response_revision:8396; }","duration":"109.782314ms","start":"2026-07-27T20:23:14.209971Z","end":"2026-07-27T20:23:14.319753Z","steps":["trace[1638641098] 'agreement among raft nodes before linearized reading' (duration: 109.714093ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:14.319754Z","caller":"traceutil/trace.go:171","msg":"trace[589409297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8396; }","duration":"155.306316ms","start":"2026-07-27T20:23:14.164431Z","end":"2026-07-27T20:23:14.319737Z","steps":["trace[589409297] 'agreement among raft nodes before linearized reading' (duration: 154.788505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:14.319959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.97838ms","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-07-27T20:23:14.319999Z","caller":"traceutil/trace.go:171","msg":"trace[852198194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8396; }","duration":"143.046701ms","start":"2026-07-27T20:23:14.176941Z","end":"2026-07-27T20:23:14.319988Z","steps":["trace[852198194] 'agreement among raft nodes before linearized reading' (duration: 142.947599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:14.320254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.723464ms","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-07-27T20:23:14.320343Z","caller":"traceutil/trace.go:171","msg":"trace[252150809] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8396; }","duration":"143.887308ms","start":"2026-07-27T20:23:14.176434Z","end":"2026-07-27T20:23:14.320322Z","steps":["trace[252150809] 'agreement among raft nodes before linearized reading' (duration: 143.581972ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:14.492654Z","caller":"traceutil/trace.go:171","msg":"trace[1930245904] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"165.98076ms","start":"2026-07-27T20:23:14.326652Z","end":"2026-07-27T20:23:14.492632Z","steps":["trace[1930245904] 'process raft request' (duration: 165.860258ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:14.494259Z","caller":"traceutil/trace.go:171","msg":"trace[670329712] transaction","detail":"{read_only:false; response_revision:8400; number_of_response:1; }","duration":"166.714295ms","start":"2026-07-27T20:23:14.327527Z","end":"2026-07-27T20:23:14.494242Z","steps":["trace[670329712] 'process raft request' (duration: 166.676884ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:14.494525Z","caller":"traceutil/trace.go:171","msg":"trace[1886803896] transaction","detail":"{read_only:false; response_revision:8398; number_of_response:1; }","duration":"167.655583ms","start":"2026-07-27T20:23:14.326859Z","end":"2026-07-27T20:23:14.494515Z","steps":["trace[1886803896] 'process raft request' (duration: 167.236204ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:14.494676Z","caller":"traceutil/trace.go:171","msg":"trace[1191853734] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"167.293256ms","start":"2026-07-27T20:23:14.327374Z","end":"2026-07-27T20:23:14.494667Z","steps":["trace[1191853734] 'process raft request' (duration: 166.796746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:16.943859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.686192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:23:16.943944Z","caller":"traceutil/trace.go:171","msg":"trace[1178771538] transaction","detail":"{read_only:false; response_revision:8415; number_of_response:1; }","duration":"262.092192ms","start":"2026-07-27T20:23:16.681837Z","end":"2026-07-27T20:23:16.943929Z","steps":["trace[1178771538] 'process raft request' (duration: 141.272327ms)","trace[1178771538] 'compare' (duration: 120.57307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:23:26.857766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.222869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:23:26.857978Z","caller":"traceutil/trace.go:171","msg":"trace[1454512155] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"243.70749ms","start":"2026-07-27T20:23:26.614249Z","end":"2026-07-27T20:23:26.857956Z","steps":["trace[1454512155] 'process raft request' (duration: 141.217626ms)","trace[1454512155] 'compare' (duration: 101.843481ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:23:26.858121Z","caller":"traceutil/trace.go:171","msg":"trace[882213951] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"243.66546ms","start":"2026-07-27T20:23:26.614423Z","end":"2026-07-27T20:23:26.858089Z","steps":["trace[882213951] 'process raft request' (duration: 243.438655ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:26.858166Z","caller":"traceutil/trace.go:171","msg":"trace[1651383584] transaction","detail":"{read_only:false; response_revision:8510; number_of_response:1; }","duration":"152.638345ms","start":"2026-07-27T20:23:26.705482Z","end":"2026-07-27T20:23:26.858121Z","steps":["trace[1651383584] 'process raft request' (duration: 152.553704ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:26.990137Z","caller":"traceutil/trace.go:171","msg":"trace[1908619224] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"155.410378ms","start":"2026-07-27T20:23:26.834698Z","end":"2026-07-27T20:23:26.990108Z","steps":["trace[1908619224] 'process raft request' (duration: 140.676196ms)","trace[1908619224] 'compare' (duration: 14.60158ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:23:27.011941Z","caller":"traceutil/trace.go:171","msg":"trace[365935400] transaction","detail":"{read_only:false; response_revision:8512; number_of_response:1; }","duration":"146.777893ms","start":"2026-07-27T20:23:26.86506Z","end":"2026-07-27T20:23:27.011838Z","steps":["trace[365935400] 'process raft request' (duration: 146.508128ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:27.135153Z","caller":"traceutil/trace.go:171","msg":"trace[1830334303] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"114.25762ms","start":"2026-07-27T20:23:27.020868Z","end":"2026-07-27T20:23:27.135126Z","steps":["trace[1830334303] 'process raft request' (duration: 114.075627ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:28.242529Z","caller":"traceutil/trace.go:171","msg":"trace[99666728] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"148.921034ms","start":"2026-07-27T20:23:28.093586Z","end":"2026-07-27T20:23:28.242507Z","steps":["trace[99666728] 'process raft request' (duration: 148.775971ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:43.720848Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000233d.wal"} {"level":"info","ts":"2026-07-27T20:25:37.966516Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T20:25:37.971893Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-27T20:25:37.971974Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-27T20:28:04.918822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8302} {"level":"info","ts":"2026-07-27T20:28:04.993774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8302,"took":"72.455619ms","hash":4066784102,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":26742784,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-27T20:28:04.99387Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4066784102,"revision":8302,"compact-revision":6128} {"level":"info","ts":"2026-07-27T20:33:04.926271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10985} {"level":"info","ts":"2026-07-27T20:33:05.011794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10985,"took":"81.815469ms","hash":1586638440,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":28278784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-27T20:33:05.011875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1586638440,"revision":10985,"compact-revision":8302} {"level":"info","ts":"2026-07-27T20:34:12.429715Z","caller":"traceutil/trace.go:171","msg":"trace[2069358740] transaction","detail":"{read_only:false; response_revision:13470; number_of_response:1; }","duration":"138.828631ms","start":"2026-07-27T20:34:12.290854Z","end":"2026-07-27T20:34:12.429683Z","steps":["trace[2069358740] 'process raft request' (duration: 138.617977ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:34:33.732212Z","caller":"traceutil/trace.go:171","msg":"trace[1816664558] transaction","detail":"{read_only:false; response_revision:13585; number_of_response:1; }","duration":"109.003468ms","start":"2026-07-27T20:34:33.623187Z","end":"2026-07-27T20:34:33.73219Z","steps":["trace[1816664558] 'process raft request' (duration: 108.663111ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:34:33.857031Z","caller":"traceutil/trace.go:171","msg":"trace[1204394055] transaction","detail":"{read_only:false; response_revision:13586; number_of_response:1; }","duration":"120.919295ms","start":"2026-07-27T20:34:33.736091Z","end":"2026-07-27T20:34:33.85701Z","steps":["trace[1204394055] 'process raft request' (duration: 119.730772ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:34:33.857138Z","caller":"traceutil/trace.go:171","msg":"trace[113806819] transaction","detail":"{read_only:false; response_revision:13587; number_of_response:1; }","duration":"119.944976ms","start":"2026-07-27T20:34:33.737173Z","end":"2026-07-27T20:34:33.857118Z","steps":["trace[113806819] 'process raft request' (duration: 119.803054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:34:48.968362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.169508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:34:48.968451Z","caller":"traceutil/trace.go:171","msg":"trace[997924087] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:13679; }","duration":"277.302501ms","start":"2026-07-27T20:34:48.691128Z","end":"2026-07-27T20:34:48.968431Z","steps":["trace[997924087] 'count revisions from in-memory index tree' (duration: 277.092567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:34:48.968647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.368542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:34:48.968739Z","caller":"traceutil/trace.go:171","msg":"trace[1303785627] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:13679; }","duration":"147.505634ms","start":"2026-07-27T20:34:48.821215Z","end":"2026-07-27T20:34:48.968721Z","steps":["trace[1303785627] 'count revisions from in-memory index tree' (duration: 147.301891ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:34:49.023404Z","caller":"traceutil/trace.go:171","msg":"trace[1883711805] transaction","detail":"{read_only:false; response_revision:13680; number_of_response:1; }","duration":"100.986014ms","start":"2026-07-27T20:34:48.922395Z","end":"2026-07-27T20:34:49.023381Z","steps":["trace[1883711805] 'process raft request' (duration: 100.821001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:35:09.37586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.052503ms","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-07-27T20:35:09.376014Z","caller":"traceutil/trace.go:171","msg":"trace[1746103877] linearizableReadLoop","detail":"{readStateIndex:14462; appliedIndex:14461; }","duration":"100.454104ms","start":"2026-07-27T20:35:09.275535Z","end":"2026-07-27T20:35:09.375989Z","steps":["trace[1746103877] 'read index received' (duration: 96.443787ms)","trace[1746103877] 'applied index is now lower than readState.Index' (duration: 4.009247ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:35:09.376059Z","caller":"traceutil/trace.go:171","msg":"trace[849973669] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:13977; }","duration":"174.277928ms","start":"2026-07-27T20:35:09.201755Z","end":"2026-07-27T20:35:09.376033Z","steps":["trace[849973669] 'range keys from in-memory index tree' (duration: 174.030763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:35:09.376301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.716329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-398aa827-644e-494c-90e3-16d700471047\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-07-27T20:35:09.376375Z","caller":"traceutil/trace.go:171","msg":"trace[485258351] transaction","detail":"{read_only:false; response_revision:13978; number_of_response:1; }","duration":"123.915094ms","start":"2026-07-27T20:35:09.252443Z","end":"2026-07-27T20:35:09.376358Z","steps":["trace[485258351] 'process raft request' (duration: 119.54633ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:35:09.376377Z","caller":"traceutil/trace.go:171","msg":"trace[500465398] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-398aa827-644e-494c-90e3-16d700471047; range_end:; response_count:1; response_revision:13978; }","duration":"100.831762ms","start":"2026-07-27T20:35:09.275502Z","end":"2026-07-27T20:35:09.376333Z","steps":["trace[500465398] 'agreement among raft nodes before linearized reading' (duration: 100.580937ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:38:04.931632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13015} {"level":"info","ts":"2026-07-27T20:38:05.022304Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13015,"took":"88.26536ms","hash":3083849262,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":31010816,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-27T20:38:05.022363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3083849262,"revision":13015,"compact-revision":10985} {"level":"warn","ts":"2026-07-27T20:42:04.299328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.965938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:42:04.299679Z","caller":"traceutil/trace.go:171","msg":"trace[1553508773] transaction","detail":"{read_only:false; response_revision:17271; number_of_response:1; }","duration":"138.281849ms","start":"2026-07-27T20:42:04.161367Z","end":"2026-07-27T20:42:04.299649Z","steps":["trace[1553508773] 'process raft request' (duration: 138.067045ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:42:04.299708Z","caller":"traceutil/trace.go:171","msg":"trace[1304664733] transaction","detail":"{read_only:false; response_revision:17270; number_of_response:1; }","duration":"141.297127ms","start":"2026-07-27T20:42:04.158341Z","end":"2026-07-27T20:42:04.299638Z","steps":["trace[1304664733] 'process raft request' (duration: 32.900211ms)","trace[1304664733] 'compare' (duration: 107.741004ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:42:10.427983Z","caller":"traceutil/trace.go:171","msg":"trace[1126428930] transaction","detail":"{read_only:false; response_revision:17304; number_of_response:1; }","duration":"101.728969ms","start":"2026-07-27T20:42:10.326225Z","end":"2026-07-27T20:42:10.427954Z","steps":["trace[1126428930] 'process raft request' (duration: 92.293399ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:42:10.790077Z","caller":"traceutil/trace.go:171","msg":"trace[1622176551] transaction","detail":"{read_only:false; response_revision:17306; number_of_response:1; }","duration":"269.737419ms","start":"2026-07-27T20:42:10.520301Z","end":"2026-07-27T20:42:10.790038Z","steps":["trace[1622176551] 'process raft request' (duration: 201.348018ms)","trace[1622176551] 'compare' (duration: 68.248418ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:42:10.790082Z","caller":"traceutil/trace.go:171","msg":"trace[38900658] linearizableReadLoop","detail":"{readStateIndex:17895; appliedIndex:17894; }","duration":"198.523864ms","start":"2026-07-27T20:42:10.591534Z","end":"2026-07-27T20:42:10.790057Z","steps":["trace[38900658] 'read index received' (duration: 130.178804ms)","trace[38900658] 'applied index is now lower than readState.Index' (duration: 68.3438ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:42:10.790374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.82523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-mlmj5\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-07-27T20:42:10.790438Z","caller":"traceutil/trace.go:171","msg":"trace[955173911] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-mlmj5; range_end:; response_count:1; response_revision:17306; }","duration":"198.924121ms","start":"2026-07-27T20:42:10.591499Z","end":"2026-07-27T20:42:10.790423Z","steps":["trace[955173911] 'agreement among raft nodes before linearized reading' (duration: 198.636856ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:42:11.114047Z","caller":"traceutil/trace.go:171","msg":"trace[1087759979] transaction","detail":"{read_only:false; response_revision:17310; number_of_response:1; }","duration":"259.451471ms","start":"2026-07-27T20:42:10.854572Z","end":"2026-07-27T20:42:11.114023Z","steps":["trace[1087759979] 'process raft request' (duration: 259.306788ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:42:11.296538Z","caller":"traceutil/trace.go:171","msg":"trace[1477906299] linearizableReadLoop","detail":"{readStateIndex:17900; appliedIndex:17899; }","duration":"329.83856ms","start":"2026-07-27T20:42:10.966663Z","end":"2026-07-27T20:42:11.296501Z","steps":["trace[1477906299] 'read index received' (duration: 147.872513ms)","trace[1477906299] 'applied index is now lower than readState.Index' (duration: 181.964597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:42:11.296903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.142856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:42:11.296968Z","caller":"traceutil/trace.go:171","msg":"trace[2031662673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17311; }","duration":"329.219407ms","start":"2026-07-27T20:42:10.967733Z","end":"2026-07-27T20:42:11.296953Z","steps":["trace[2031662673] 'agreement among raft nodes before linearized reading' (duration: 329.113685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:42:11.297011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:42:10.967701Z","time spent":"329.302899ms","remote":"127.0.0.1:59126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T20:42:11.297184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.37179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-27T20:42:11.297221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.838601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:42:11.297231Z","caller":"traceutil/trace.go:171","msg":"trace[239676345] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:17311; }","duration":"330.588404ms","start":"2026-07-27T20:42:10.966628Z","end":"2026-07-27T20:42:11.297217Z","steps":["trace[239676345] 'agreement among raft nodes before linearized reading' (duration: 330.250938ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:42:11.297255Z","caller":"traceutil/trace.go:171","msg":"trace[809419466] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:0; response_revision:17311; }","duration":"172.901523ms","start":"2026-07-27T20:42:11.124341Z","end":"2026-07-27T20:42:11.297243Z","steps":["trace[809419466] 'agreement among raft nodes before linearized reading' (duration: 172.847062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:42:11.297287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:42:10.966615Z","time spent":"330.663125ms","remote":"127.0.0.1:59240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":74,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:42:11.297591Z","caller":"traceutil/trace.go:171","msg":"trace[1583545698] transaction","detail":"{read_only:false; response_revision:17311; number_of_response:1; }","duration":"368.251735ms","start":"2026-07-27T20:42:10.929308Z","end":"2026-07-27T20:42:11.29756Z","steps":["trace[1583545698] 'process raft request' (duration: 333.967869ms)","trace[1583545698] 'compare' (duration: 32.851239ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:42:11.297716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:42:10.929274Z","time spent":"368.345018ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:42:18.336889Z","caller":"traceutil/trace.go:171","msg":"trace[834367676] transaction","detail":"{read_only:false; response_revision:17446; number_of_response:1; }","duration":"105.808477ms","start":"2026-07-27T20:42:18.231048Z","end":"2026-07-27T20:42:18.336857Z","steps":["trace[834367676] 'process raft request' (duration: 105.627104ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:43:04.938268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15375} {"level":"info","ts":"2026-07-27T20:43:05.035324Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15375,"took":"93.98028ms","hash":1539136038,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":33624064,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-27T20:43:05.035378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1539136038,"revision":15375,"compact-revision":13015} {"level":"info","ts":"2026-07-27T20:46:13.14411Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T20:46:13.152516Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-27T20:46:13.15286Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-27T20:47:35.820611Z","caller":"traceutil/trace.go:171","msg":"trace[383443235] transaction","detail":"{read_only:false; response_revision:19877; number_of_response:1; }","duration":"168.560208ms","start":"2026-07-27T20:47:35.652026Z","end":"2026-07-27T20:47:35.820586Z","steps":["trace[383443235] 'process raft request' (duration: 168.410325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:47:36.15203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.131958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:47:36.152111Z","caller":"traceutil/trace.go:171","msg":"trace[1639222625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19877; }","duration":"183.26459ms","start":"2026-07-27T20:47:35.968829Z","end":"2026-07-27T20:47:36.152093Z","steps":["trace[1639222625] 'range keys from in-memory index tree' (duration: 183.043926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:47:36.152386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.569912ms","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-07-27T20:47:36.152558Z","caller":"traceutil/trace.go:171","msg":"trace[634688192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19877; }","duration":"235.860856ms","start":"2026-07-27T20:47:35.916667Z","end":"2026-07-27T20:47:36.152528Z","steps":["trace[634688192] 'range keys from in-memory index tree' (duration: 235.346457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:47:36.152612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.522882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:47:36.154574Z","caller":"traceutil/trace.go:171","msg":"trace[9185781] transaction","detail":"{read_only:false; response_revision:19878; number_of_response:1; }","duration":"171.588017ms","start":"2026-07-27T20:47:35.982927Z","end":"2026-07-27T20:47:36.154515Z","steps":["trace[9185781] 'process raft request' (duration: 42.073376ms)","trace[9185781] 'compare' (duration: 127.046824ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:47:36.15529Z","caller":"traceutil/trace.go:171","msg":"trace[749490797] linearizableReadLoop","detail":"{readStateIndex:20551; appliedIndex:20549; }","duration":"144.368185ms","start":"2026-07-27T20:47:36.010904Z","end":"2026-07-27T20:47:36.155272Z","steps":["trace[749490797] 'read index received' (duration: 14.09427ms)","trace[749490797] 'applied index is now lower than readState.Index' (duration: 130.272505ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:47:36.155394Z","caller":"traceutil/trace.go:171","msg":"trace[358945944] transaction","detail":"{read_only:false; response_revision:19879; number_of_response:1; }","duration":"154.406908ms","start":"2026-07-27T20:47:36.000956Z","end":"2026-07-27T20:47:36.155363Z","steps":["trace[358945944] 'process raft request' (duration: 154.148082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:47:36.155657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.839277ms","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-07-27T20:47:36.155714Z","caller":"traceutil/trace.go:171","msg":"trace[1292585249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19879; }","duration":"129.940329ms","start":"2026-07-27T20:47:36.025761Z","end":"2026-07-27T20:47:36.155701Z","steps":["trace[1292585249] 'agreement among raft nodes before linearized reading' (duration: 129.688284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:47:36.156242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.388805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-27T20:47:36.156287Z","caller":"traceutil/trace.go:171","msg":"trace[187323795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19879; }","duration":"145.498367ms","start":"2026-07-27T20:47:36.010778Z","end":"2026-07-27T20:47:36.156276Z","steps":["trace[187323795] 'agreement among raft nodes before linearized reading' (duration: 144.626961ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:47:36.653904Z","caller":"traceutil/trace.go:171","msg":"trace[1115708636] transaction","detail":"{read_only:false; response_revision:19883; number_of_response:1; }","duration":"171.661468ms","start":"2026-07-27T20:47:36.482219Z","end":"2026-07-27T20:47:36.653881Z","steps":["trace[1115708636] 'process raft request' (duration: 171.535385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:47:36.905843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.920275ms","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-07-27T20:47:36.905934Z","caller":"traceutil/trace.go:171","msg":"trace[647956759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19883; }","duration":"111.071468ms","start":"2026-07-27T20:47:36.794837Z","end":"2026-07-27T20:47:36.905909Z","steps":["trace[647956759] 'range keys from in-memory index tree' (duration: 110.70855ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:47:37.214815Z","caller":"traceutil/trace.go:171","msg":"trace[525029970] transaction","detail":"{read_only:false; response_revision:19885; number_of_response:1; }","duration":"124.195589ms","start":"2026-07-27T20:47:37.09058Z","end":"2026-07-27T20:47:37.214775Z","steps":["trace[525029970] 'process raft request' (duration: 124.012386ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:47:37.459828Z","caller":"traceutil/trace.go:171","msg":"trace[595703426] linearizableReadLoop","detail":"{readStateIndex:20558; appliedIndex:20557; }","duration":"125.956362ms","start":"2026-07-27T20:47:37.333845Z","end":"2026-07-27T20:47:37.459801Z","steps":["trace[595703426] 'read index received' (duration: 50.326474ms)","trace[595703426] 'applied index is now lower than readState.Index' (duration: 75.628758ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:47:37.460038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.171496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:47:37.460035Z","caller":"traceutil/trace.go:171","msg":"trace[123407706] transaction","detail":"{read_only:false; response_revision:19886; number_of_response:1; }","duration":"200.891757ms","start":"2026-07-27T20:47:37.258994Z","end":"2026-07-27T20:47:37.459886Z","steps":["trace[123407706] 'process raft request' (duration: 125.257088ms)","trace[123407706] 'compare' (duration: 75.411585ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:47:37.460078Z","caller":"traceutil/trace.go:171","msg":"trace[1178331381] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:19886; }","duration":"126.268368ms","start":"2026-07-27T20:47:37.333799Z","end":"2026-07-27T20:47:37.460067Z","steps":["trace[1178331381] 'agreement among raft nodes before linearized reading' (duration: 126.116446ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:48:04.943767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17958} {"level":"info","ts":"2026-07-27T20:48:05.057035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17958,"took":"110.429086ms","hash":276295366,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":29519872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-27T20:48:05.057154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":276295366,"revision":17958,"compact-revision":15375} {"level":"info","ts":"2026-07-27T20:51:27.540246Z","caller":"traceutil/trace.go:171","msg":"trace[456958523] linearizableReadLoop","detail":"{readStateIndex:23058; appliedIndex:23057; }","duration":"100.559186ms","start":"2026-07-27T20:51:27.439669Z","end":"2026-07-27T20:51:27.540228Z","steps":["trace[456958523] 'read index received' (duration: 100.370103ms)","trace[456958523] 'applied index is now lower than readState.Index' (duration: 188.203µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:51:27.540305Z","caller":"traceutil/trace.go:171","msg":"trace[615735262] transaction","detail":"{read_only:false; response_revision:22316; number_of_response:1; }","duration":"114.011874ms","start":"2026-07-27T20:51:27.426259Z","end":"2026-07-27T20:51:27.540271Z","steps":["trace[615735262] 'process raft request' (duration: 113.82979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:51:27.540428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.540576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:51:27.540463Z","caller":"traceutil/trace.go:171","msg":"trace[1916550452] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:22316; }","duration":"100.595407ms","start":"2026-07-27T20:51:27.439859Z","end":"2026-07-27T20:51:27.540454Z","steps":["trace[1916550452] 'agreement among raft nodes before linearized reading' (duration: 100.481745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:51:27.540569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.840251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-27T20:51:27.540673Z","caller":"traceutil/trace.go:171","msg":"trace[272195768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22316; }","duration":"101.056896ms","start":"2026-07-27T20:51:27.439594Z","end":"2026-07-27T20:51:27.54065Z","steps":["trace[272195768] 'agreement among raft nodes before linearized reading' (duration: 100.73943ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:53:04.948666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20099} {"level":"info","ts":"2026-07-27T20:53:05.049202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20099,"took":"96.948817ms","hash":1276816325,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":43184128,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-27T20:53:05.049474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1276816325,"revision":20099,"compact-revision":17958} {"level":"info","ts":"2026-07-27T20:54:58.673161Z","caller":"traceutil/trace.go:171","msg":"trace[1568646571] transaction","detail":"{read_only:false; response_revision:24423; number_of_response:1; }","duration":"105.852318ms","start":"2026-07-27T20:54:58.567285Z","end":"2026-07-27T20:54:58.673137Z","steps":["trace[1568646571] 'process raft request' (duration: 105.683874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:54:59.001437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.807921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-27T20:54:59.001529Z","caller":"traceutil/trace.go:171","msg":"trace[2101322454] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24426; }","duration":"134.929644ms","start":"2026-07-27T20:54:58.866575Z","end":"2026-07-27T20:54:59.001505Z","steps":["trace[2101322454] 'range keys from in-memory index tree' (duration: 134.664749ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:54:59.162533Z","caller":"traceutil/trace.go:171","msg":"trace[887261946] transaction","detail":"{read_only:false; response_revision:24428; number_of_response:1; }","duration":"153.921358ms","start":"2026-07-27T20:54:59.008588Z","end":"2026-07-27T20:54:59.162509Z","steps":["trace[887261946] 'process raft request' (duration: 152.053802ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:54:59.162763Z","caller":"traceutil/trace.go:171","msg":"trace[1221175947] linearizableReadLoop","detail":"{readStateIndex:25226; appliedIndex:25225; }","duration":"106.442848ms","start":"2026-07-27T20:54:59.056309Z","end":"2026-07-27T20:54:59.162752Z","steps":["trace[1221175947] 'read index received' (duration: 104.351889ms)","trace[1221175947] 'applied index is now lower than readState.Index' (duration: 2.089829ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:54:59.162992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.676453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:54:59.163034Z","caller":"traceutil/trace.go:171","msg":"trace[1559580156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24429; }","duration":"106.730884ms","start":"2026-07-27T20:54:59.056293Z","end":"2026-07-27T20:54:59.163024Z","steps":["trace[1559580156] 'agreement among raft nodes before linearized reading' (duration: 106.654083ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:54:59.359993Z","caller":"traceutil/trace.go:171","msg":"trace[1292856654] transaction","detail":"{read_only:false; response_revision:24430; number_of_response:1; }","duration":"190.683432ms","start":"2026-07-27T20:54:59.169281Z","end":"2026-07-27T20:54:59.359965Z","steps":["trace[1292856654] 'process raft request' (duration: 96.397216ms)","trace[1292856654] 'compare' (duration: 94.180184ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:54:59.360362Z","caller":"traceutil/trace.go:171","msg":"trace[1361578865] linearizableReadLoop","detail":"{readStateIndex:25229; appliedIndex:25227; }","duration":"117.148163ms","start":"2026-07-27T20:54:59.243198Z","end":"2026-07-27T20:54:59.360346Z","steps":["trace[1361578865] 'read index received' (duration: 22.582372ms)","trace[1361578865] 'applied index is now lower than readState.Index' (duration: 94.563841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:54:59.36049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.276176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:54:59.360532Z","caller":"traceutil/trace.go:171","msg":"trace[416705092] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24431; }","duration":"117.368307ms","start":"2026-07-27T20:54:59.24315Z","end":"2026-07-27T20:54:59.360519Z","steps":["trace[416705092] 'agreement among raft nodes before linearized reading' (duration: 117.247995ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:54:59.36052Z","caller":"traceutil/trace.go:171","msg":"trace[1986406741] transaction","detail":"{read_only:false; response_revision:24431; number_of_response:1; }","duration":"134.775121ms","start":"2026-07-27T20:54:59.225713Z","end":"2026-07-27T20:54:59.360488Z","steps":["trace[1986406741] 'process raft request' (duration: 134.322463ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:03.743873Z","caller":"traceutil/trace.go:171","msg":"trace[368431284] transaction","detail":"{read_only:false; response_revision:24486; number_of_response:1; }","duration":"218.22692ms","start":"2026-07-27T20:55:03.525618Z","end":"2026-07-27T20:55:03.743845Z","steps":["trace[368431284] 'process raft request' (duration: 218.068127ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:03.948096Z","caller":"traceutil/trace.go:171","msg":"trace[925460738] linearizableReadLoop","detail":"{readStateIndex:25287; appliedIndex:25285; }","duration":"106.823206ms","start":"2026-07-27T20:55:03.841252Z","end":"2026-07-27T20:55:03.948075Z","steps":["trace[925460738] 'read index received' (duration: 59.654072ms)","trace[925460738] 'applied index is now lower than readState.Index' (duration: 47.167974ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:03.948383Z","caller":"traceutil/trace.go:171","msg":"trace[1184282680] transaction","detail":"{read_only:false; response_revision:24487; number_of_response:1; }","duration":"204.881473ms","start":"2026-07-27T20:55:03.743487Z","end":"2026-07-27T20:55:03.948369Z","steps":["trace[1184282680] 'process raft request' (duration: 157.395184ms)","trace[1184282680] 'compare' (duration: 46.704565ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:03.948566Z","caller":"traceutil/trace.go:171","msg":"trace[1811486571] transaction","detail":"{read_only:false; response_revision:24488; number_of_response:1; }","duration":"198.93087ms","start":"2026-07-27T20:55:03.749626Z","end":"2026-07-27T20:55:03.948557Z","steps":["trace[1811486571] 'process raft request' (duration: 198.39744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:03.948759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.488028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:03.948807Z","caller":"traceutil/trace.go:171","msg":"trace[306334737] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:24488; }","duration":"107.61983ms","start":"2026-07-27T20:55:03.841174Z","end":"2026-07-27T20:55:03.948794Z","steps":["trace[306334737] 'agreement among raft nodes before linearized reading' (duration: 107.537259ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:04.063083Z","caller":"traceutil/trace.go:171","msg":"trace[1083330480] transaction","detail":"{read_only:false; response_revision:24489; number_of_response:1; }","duration":"108.742023ms","start":"2026-07-27T20:55:03.954289Z","end":"2026-07-27T20:55:04.063031Z","steps":["trace[1083330480] 'process raft request' (duration: 106.53981ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:04.06327Z","caller":"traceutil/trace.go:171","msg":"trace[1592109266] transaction","detail":"{read_only:false; response_revision:24490; number_of_response:1; }","duration":"108.938426ms","start":"2026-07-27T20:55:03.954294Z","end":"2026-07-27T20:55:04.063232Z","steps":["trace[1592109266] 'process raft request' (duration: 108.655481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:04.352011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.867763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:04.352098Z","caller":"traceutil/trace.go:171","msg":"trace[890033224] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24490; }","duration":"170.000256ms","start":"2026-07-27T20:55:04.182078Z","end":"2026-07-27T20:55:04.352078Z","steps":["trace[890033224] 'range keys from in-memory index tree' (duration: 169.784761ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:04.63546Z","caller":"traceutil/trace.go:171","msg":"trace[1005301694] transaction","detail":"{read_only:false; response_revision:24491; number_of_response:1; }","duration":"129.582602ms","start":"2026-07-27T20:55:04.505853Z","end":"2026-07-27T20:55:04.635435Z","steps":["trace[1005301694] 'process raft request' (duration: 129.419309ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:04.835623Z","caller":"traceutil/trace.go:171","msg":"trace[1581269149] transaction","detail":"{read_only:false; response_revision:24492; number_of_response:1; }","duration":"143.819495ms","start":"2026-07-27T20:55:04.691777Z","end":"2026-07-27T20:55:04.835597Z","steps":["trace[1581269149] 'process raft request' (duration: 143.627931ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:05.17801Z","caller":"traceutil/trace.go:171","msg":"trace[1684352284] transaction","detail":"{read_only:false; response_revision:24496; number_of_response:1; }","duration":"102.579894ms","start":"2026-07-27T20:55:05.075412Z","end":"2026-07-27T20:55:05.177992Z","steps":["trace[1684352284] 'process raft request' (duration: 102.461832ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:05.426971Z","caller":"traceutil/trace.go:171","msg":"trace[1286761579] transaction","detail":"{read_only:false; response_revision:24497; number_of_response:1; }","duration":"239.419386ms","start":"2026-07-27T20:55:05.18752Z","end":"2026-07-27T20:55:05.42694Z","steps":["trace[1286761579] 'process raft request' (duration: 239.217442ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:05.530219Z","caller":"traceutil/trace.go:171","msg":"trace[1408648766] transaction","detail":"{read_only:false; response_revision:24498; number_of_response:1; }","duration":"338.669027ms","start":"2026-07-27T20:55:05.191524Z","end":"2026-07-27T20:55:05.530193Z","steps":["trace[1408648766] 'process raft request' (duration: 317.837218ms)","trace[1408648766] 'compare' (duration: 20.600254ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:05.530369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:05.191502Z","time spent":"338.782308ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:55:05.530398Z","caller":"traceutil/trace.go:171","msg":"trace[587020611] linearizableReadLoop","detail":"{readStateIndex:25297; appliedIndex:25295; }","duration":"312.129998ms","start":"2026-07-27T20:55:05.218245Z","end":"2026-07-27T20:55:05.530375Z","steps":["trace[587020611] 'read index received' (duration: 208.233518ms)","trace[587020611] 'applied index is now lower than readState.Index' (duration: 103.89515ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:05.530503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.23658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:05.530532Z","caller":"traceutil/trace.go:171","msg":"trace[1547114132] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24499; }","duration":"312.312902ms","start":"2026-07-27T20:55:05.218211Z","end":"2026-07-27T20:55:05.530524Z","steps":["trace[1547114132] 'agreement among raft nodes before linearized reading' (duration: 312.23977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:05.530559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:05.218198Z","time spent":"312.352732ms","remote":"127.0.0.1:59354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:55:05.701906Z","caller":"traceutil/trace.go:171","msg":"trace[580528660] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"148.420182ms","start":"2026-07-27T20:55:05.553461Z","end":"2026-07-27T20:55:05.701881Z","steps":["trace[580528660] 'process raft request' (duration: 135.382782ms)","trace[580528660] 'compare' (duration: 12.937358ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:06.025455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.59086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:55:06.025581Z","caller":"traceutil/trace.go:171","msg":"trace[651153827] transaction","detail":"{read_only:false; response_revision:24501; number_of_response:1; }","duration":"276.071708ms","start":"2026-07-27T20:55:05.749482Z","end":"2026-07-27T20:55:06.025554Z","steps":["trace[651153827] 'process raft request' (duration: 96.311834ms)","trace[651153827] 'compare' (duration: 178.813275ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:06.025688Z","caller":"traceutil/trace.go:171","msg":"trace[1258547943] linearizableReadLoop","detail":"{readStateIndex:25300; appliedIndex:25299; }","duration":"216.996226ms","start":"2026-07-27T20:55:05.80866Z","end":"2026-07-27T20:55:06.025656Z","steps":["trace[1258547943] 'read index received' (duration: 37.05757ms)","trace[1258547943] 'applied index is now lower than readState.Index' (duration: 179.927296ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:06.025875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.21325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:55:06.025917Z","caller":"traceutil/trace.go:171","msg":"trace[2039216835] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:24502; }","duration":"217.306302ms","start":"2026-07-27T20:55:05.808602Z","end":"2026-07-27T20:55:06.025909Z","steps":["trace[2039216835] 'agreement among raft nodes before linearized reading' (duration: 217.17151ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:06.278393Z","caller":"traceutil/trace.go:171","msg":"trace[10908] transaction","detail":"{read_only:false; response_revision:24503; number_of_response:1; }","duration":"323.957194ms","start":"2026-07-27T20:55:05.954411Z","end":"2026-07-27T20:55:06.278368Z","steps":["trace[10908] 'process raft request' (duration: 323.786291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:06.278542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:05.954399Z","time spent":"324.067066ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:55:06.278655Z","caller":"traceutil/trace.go:171","msg":"trace[300879247] linearizableReadLoop","detail":"{readStateIndex:25302; appliedIndex:25302; }","duration":"252.869423ms","start":"2026-07-27T20:55:06.025757Z","end":"2026-07-27T20:55:06.278627Z","steps":["trace[300879247] 'read index received' (duration: 252.850063ms)","trace[300879247] 'applied index is now lower than readState.Index' (duration: 17.94µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:06.278689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.014706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:06.27875Z","caller":"traceutil/trace.go:171","msg":"trace[1659888515] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:24503; }","duration":"313.082927ms","start":"2026-07-27T20:55:05.965652Z","end":"2026-07-27T20:55:06.278735Z","steps":["trace[1659888515] 'agreement among raft nodes before linearized reading' (duration: 312.984614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:06.278755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.18163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:55:06.278769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.64652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:55:06.278804Z","caller":"traceutil/trace.go:171","msg":"trace[1049554543] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:24503; }","duration":"324.256531ms","start":"2026-07-27T20:55:05.954534Z","end":"2026-07-27T20:55:06.278791Z","steps":["trace[1049554543] 'agreement among raft nodes before linearized reading' (duration: 324.17885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:06.278796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:05.965614Z","time spent":"313.172309ms","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:55:06.27884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:05.954525Z","time spent":"324.306232ms","remote":"127.0.0.1:46812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:55:06.278842Z","caller":"traceutil/trace.go:171","msg":"trace[977545770] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:24503; }","duration":"310.830544ms","start":"2026-07-27T20:55:05.967995Z","end":"2026-07-27T20:55:06.278825Z","steps":["trace[977545770] 'agreement among raft nodes before linearized reading' (duration: 310.637989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:06.279324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:05.967987Z","time spent":"311.322513ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":30,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:55:06.279491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.829072ms","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-07-27T20:55:06.279586Z","caller":"traceutil/trace.go:171","msg":"trace[1850183145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24503; }","duration":"206.952734ms","start":"2026-07-27T20:55:06.072621Z","end":"2026-07-27T20:55:06.279574Z","steps":["trace[1850183145] 'agreement among raft nodes before linearized reading' (duration: 206.456235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:06.617649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.915063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:55:06.617814Z","caller":"traceutil/trace.go:171","msg":"trace[1620630506] transaction","detail":"{read_only:false; response_revision:24505; number_of_response:1; }","duration":"333.873855ms","start":"2026-07-27T20:55:06.283925Z","end":"2026-07-27T20:55:06.617798Z","steps":["trace[1620630506] 'process raft request' (duration: 333.817504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:06.61788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:06.283917Z","time spent":"333.933886ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:55:06.618166Z","caller":"traceutil/trace.go:171","msg":"trace[1533427886] transaction","detail":"{read_only:false; response_revision:24504; number_of_response:1; }","duration":"336.78562ms","start":"2026-07-27T20:55:06.281368Z","end":"2026-07-27T20:55:06.618154Z","steps":["trace[1533427886] 'process raft request' (duration: 45.294048ms)","trace[1533427886] 'compare' (duration: 290.086716ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:06.618231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:06.281354Z","time spent":"336.845762ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:55:06.907003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.367319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:06.90709Z","caller":"traceutil/trace.go:171","msg":"trace[721453842] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24505; }","duration":"152.485011ms","start":"2026-07-27T20:55:06.75459Z","end":"2026-07-27T20:55:06.907075Z","steps":["trace[721453842] 'range keys from in-memory index tree' (duration: 152.308928ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:07.149224Z","caller":"traceutil/trace.go:171","msg":"trace[962317072] linearizableReadLoop","detail":"{readStateIndex:25308; appliedIndex:25305; }","duration":"234.171825ms","start":"2026-07-27T20:55:06.914988Z","end":"2026-07-27T20:55:07.14916Z","steps":["trace[962317072] 'read index received' (duration: 5.693959ms)","trace[962317072] 'applied index is now lower than readState.Index' (duration: 228.476696ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:07.150477Z","caller":"traceutil/trace.go:171","msg":"trace[1576173548] transaction","detail":"{read_only:false; response_revision:24508; number_of_response:1; }","duration":"238.200173ms","start":"2026-07-27T20:55:06.91225Z","end":"2026-07-27T20:55:07.15045Z","steps":["trace[1576173548] 'process raft request' (duration: 196.349641ms)","trace[1576173548] 'compare' (duration: 40.280602ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:07.150613Z","caller":"traceutil/trace.go:171","msg":"trace[2106004419] transaction","detail":"{read_only:false; response_revision:24509; number_of_response:1; }","duration":"238.258514ms","start":"2026-07-27T20:55:06.912346Z","end":"2026-07-27T20:55:07.150604Z","steps":["trace[2106004419] 'process raft request' (duration: 236.743875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:07.150841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.834067ms","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-07-27T20:55:07.150962Z","caller":"traceutil/trace.go:171","msg":"trace[1693207537] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24509; }","duration":"235.996551ms","start":"2026-07-27T20:55:06.914953Z","end":"2026-07-27T20:55:07.15095Z","steps":["trace[1693207537] 'agreement among raft nodes before linearized reading' (duration: 235.834877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:07.151184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.09693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:07.151212Z","caller":"traceutil/trace.go:171","msg":"trace[370374022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24509; }","duration":"180.152621ms","start":"2026-07-27T20:55:06.971051Z","end":"2026-07-27T20:55:07.151203Z","steps":["trace[370374022] 'agreement among raft nodes before linearized reading' (duration: 180.10435ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:11.659686Z","caller":"traceutil/trace.go:171","msg":"trace[314038746] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"110.114619ms","start":"2026-07-27T20:55:11.54955Z","end":"2026-07-27T20:55:11.659665Z","steps":["trace[314038746] 'process raft request' (duration: 109.930506ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:11.787957Z","caller":"traceutil/trace.go:171","msg":"trace[1467683557] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"123.989724ms","start":"2026-07-27T20:55:11.663935Z","end":"2026-07-27T20:55:11.787925Z","steps":["trace[1467683557] 'process raft request' (duration: 122.117258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:12.34601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.733267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:55:12.346124Z","caller":"traceutil/trace.go:171","msg":"trace[1100185892] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"294.654044ms","start":"2026-07-27T20:55:12.05145Z","end":"2026-07-27T20:55:12.346104Z","steps":["trace[1100185892] 'process raft request' (duration: 153.710974ms)","trace[1100185892] 'compare' (duration: 140.626794ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:12.361559Z","caller":"traceutil/trace.go:171","msg":"trace[202484398] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"219.213349ms","start":"2026-07-27T20:55:12.14233Z","end":"2026-07-27T20:55:12.361543Z","steps":["trace[202484398] 'process raft request' (duration: 219.100487ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:12.361767Z","caller":"traceutil/trace.go:171","msg":"trace[490263013] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"197.659986ms","start":"2026-07-27T20:55:12.164081Z","end":"2026-07-27T20:55:12.361741Z","steps":["trace[490263013] 'process raft request' (duration: 197.438642ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:12.530383Z","caller":"traceutil/trace.go:171","msg":"trace[1004221196] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"233.34366ms","start":"2026-07-27T20:55:12.297013Z","end":"2026-07-27T20:55:12.530356Z","steps":["trace[1004221196] 'process raft request' (duration: 228.13041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:12.80414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.38576ms","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-07-27T20:55:12.804193Z","caller":"traceutil/trace.go:171","msg":"trace[1387182246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24554; }","duration":"151.471591ms","start":"2026-07-27T20:55:12.652708Z","end":"2026-07-27T20:55:12.80418Z","steps":["trace[1387182246] 'range keys from in-memory index tree' (duration: 151.223926ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:12.933482Z","caller":"traceutil/trace.go:171","msg":"trace[1952999047] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"122.71778ms","start":"2026-07-27T20:55:12.810743Z","end":"2026-07-27T20:55:12.933461Z","steps":["trace[1952999047] 'process raft request' (duration: 122.593377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:13.261245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.281832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:55:13.261319Z","caller":"traceutil/trace.go:171","msg":"trace[1616017511] linearizableReadLoop","detail":"{readStateIndex:25357; appliedIndex:25356; }","duration":"291.253549ms","start":"2026-07-27T20:55:12.970054Z","end":"2026-07-27T20:55:13.261308Z","steps":["trace[1616017511] 'read index received' (duration: 108.797513ms)","trace[1616017511] 'applied index is now lower than readState.Index' (duration: 182.454956ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:13.26137Z","caller":"traceutil/trace.go:171","msg":"trace[2013385229] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"317.208036ms","start":"2026-07-27T20:55:12.944155Z","end":"2026-07-27T20:55:13.261363Z","steps":["trace[2013385229] 'process raft request' (duration: 134.75398ms)","trace[2013385229] 'compare' (duration: 181.958066ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:13.261405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:12.944141Z","time spent":"317.244926ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:55:13.261515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.469422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:13.261537Z","caller":"traceutil/trace.go:171","msg":"trace[1713086007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24557; }","duration":"291.522453ms","start":"2026-07-27T20:55:12.970009Z","end":"2026-07-27T20:55:13.261532Z","steps":["trace[1713086007] 'agreement among raft nodes before linearized reading' (duration: 291.463392ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:13.444031Z","caller":"traceutil/trace.go:171","msg":"trace[1915471892] linearizableReadLoop","detail":"{readStateIndex:25358; appliedIndex:25357; }","duration":"182.666659ms","start":"2026-07-27T20:55:13.261336Z","end":"2026-07-27T20:55:13.444003Z","steps":["trace[1915471892] 'read index received' (duration: 170.468595ms)","trace[1915471892] 'applied index is now lower than readState.Index' (duration: 12.196724ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:13.444157Z","caller":"traceutil/trace.go:171","msg":"trace[83866843] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"240.491976ms","start":"2026-07-27T20:55:13.203653Z","end":"2026-07-27T20:55:13.444145Z","steps":["trace[83866843] 'process raft request' (duration: 228.061198ms)","trace[83866843] 'compare' (duration: 12.01271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:13.444384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.393827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:55:13.444501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.349792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:55:13.444608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.87138ms","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-07-27T20:55:13.444628Z","caller":"traceutil/trace.go:171","msg":"trace[2139016185] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24558; }","duration":"312.492406ms","start":"2026-07-27T20:55:13.13208Z","end":"2026-07-27T20:55:13.444573Z","steps":["trace[2139016185] 'agreement among raft nodes before linearized reading' (duration: 312.133528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:13.444676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:13.132061Z","time spent":"312.604528ms","remote":"127.0.0.1:46942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:55:13.444654Z","caller":"traceutil/trace.go:171","msg":"trace[1866672942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24558; }","duration":"269.060034ms","start":"2026-07-27T20:55:13.175579Z","end":"2026-07-27T20:55:13.44464Z","steps":["trace[1866672942] 'agreement among raft nodes before linearized reading' (duration: 268.666456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:13.444397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.13588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:13.444761Z","caller":"traceutil/trace.go:171","msg":"trace[1178462464] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24558; }","duration":"263.514238ms","start":"2026-07-27T20:55:13.181229Z","end":"2026-07-27T20:55:13.444744Z","steps":["trace[1178462464] 'agreement among raft nodes before linearized reading' (duration: 263.007778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:13.444496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.219861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-27T20:55:13.444766Z","caller":"traceutil/trace.go:171","msg":"trace[515062484] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24558; }","duration":"367.596852ms","start":"2026-07-27T20:55:13.07696Z","end":"2026-07-27T20:55:13.444557Z","steps":["trace[515062484] 'agreement among raft nodes before linearized reading' (duration: 367.307615ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:13.44488Z","caller":"traceutil/trace.go:171","msg":"trace[1765737715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24558; }","duration":"274.63532ms","start":"2026-07-27T20:55:13.170215Z","end":"2026-07-27T20:55:13.44485Z","steps":["trace[1765737715] 'agreement among raft nodes before linearized reading' (duration: 274.039328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:13.444984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:13.07692Z","time spent":"368.010619ms","remote":"127.0.0.1:46920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:55:13.72522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.235376ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-27T20:55:13.725495Z","caller":"traceutil/trace.go:171","msg":"trace[1853590506] linearizableReadLoop","detail":"{readStateIndex:25359; appliedIndex:25358; }","duration":"281.38241ms","start":"2026-07-27T20:55:13.444096Z","end":"2026-07-27T20:55:13.725478Z","steps":["trace[1853590506] 'read index received' (duration: 198.684µs)","trace[1853590506] 'applied index is now lower than readState.Index' (duration: 281.182656ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:13.725605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.457307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:13.725659Z","caller":"traceutil/trace.go:171","msg":"trace[1214066202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24558; }","duration":"463.535839ms","start":"2026-07-27T20:55:13.262113Z","end":"2026-07-27T20:55:13.725649Z","steps":["trace[1214066202] 'agreement among raft nodes before linearized reading' (duration: 463.447117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:13.725723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:13.262105Z","time spent":"463.60816ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-27T20:55:13.725755Z","caller":"traceutil/trace.go:171","msg":"trace[205934128] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"276.926583ms","start":"2026-07-27T20:55:13.448802Z","end":"2026-07-27T20:55:13.725728Z","steps":["trace[205934128] 'process raft request' (duration: 276.590237ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:13.730323Z","caller":"traceutil/trace.go:171","msg":"trace[1657495053] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"277.964895ms","start":"2026-07-27T20:55:13.452338Z","end":"2026-07-27T20:55:13.730303Z","steps":["trace[1657495053] 'process raft request' (duration: 277.883513ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:13.730389Z","caller":"traceutil/trace.go:171","msg":"trace[105234024] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"281.535053ms","start":"2026-07-27T20:55:13.448827Z","end":"2026-07-27T20:55:13.730362Z","steps":["trace[105234024] 'process raft request' (duration: 276.886333ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:13.99146Z","caller":"traceutil/trace.go:171","msg":"trace[1012789956] linearizableReadLoop","detail":"{readStateIndex:25364; appliedIndex:25363; }","duration":"172.479744ms","start":"2026-07-27T20:55:13.818964Z","end":"2026-07-27T20:55:13.991444Z","steps":["trace[1012789956] 'read index received' (duration: 109.468777ms)","trace[1012789956] 'applied index is now lower than readState.Index' (duration: 63.010257ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:13.99161Z","caller":"traceutil/trace.go:171","msg":"trace[1259117155] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"193.954694ms","start":"2026-07-27T20:55:13.797648Z","end":"2026-07-27T20:55:13.991603Z","steps":["trace[1259117155] 'process raft request' (duration: 130.906936ms)","trace[1259117155] 'compare' (duration: 62.817123ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:13.991765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.788719ms","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-07-27T20:55:13.991782Z","caller":"traceutil/trace.go:171","msg":"trace[360069701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24563; }","duration":"172.81817ms","start":"2026-07-27T20:55:13.818958Z","end":"2026-07-27T20:55:13.991776Z","steps":["trace[360069701] 'agreement among raft nodes before linearized reading' (duration: 172.739698ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:15.552855Z","caller":"traceutil/trace.go:171","msg":"trace[1535204113] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"104.791807ms","start":"2026-07-27T20:55:15.448041Z","end":"2026-07-27T20:55:15.552833Z","steps":["trace[1535204113] 'process raft request' (duration: 104.631074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:23.726004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.713207ms","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-07-27T20:55:23.726102Z","caller":"traceutil/trace.go:171","msg":"trace[1973973965] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:24702; }","duration":"162.86855ms","start":"2026-07-27T20:55:23.563209Z","end":"2026-07-27T20:55:23.726078Z","steps":["trace[1973973965] 'count revisions from in-memory index tree' (duration: 162.583574ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:23.72675Z","caller":"traceutil/trace.go:171","msg":"trace[206056759] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"143.194872ms","start":"2026-07-27T20:55:23.583539Z","end":"2026-07-27T20:55:23.726734Z","steps":["trace[206056759] 'process raft request' (duration: 138.93939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:23.997677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.515169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:55:23.997979Z","caller":"traceutil/trace.go:171","msg":"trace[1957061776] linearizableReadLoop","detail":"{readStateIndex:25509; appliedIndex:25507; }","duration":"177.00672ms","start":"2026-07-27T20:55:23.820951Z","end":"2026-07-27T20:55:23.997958Z","steps":["trace[1957061776] 'read index received' (duration: 32.113875ms)","trace[1957061776] 'applied index is now lower than readState.Index' (duration: 144.890925ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:23.99799Z","caller":"traceutil/trace.go:171","msg":"trace[1188299922] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"191.962537ms","start":"2026-07-27T20:55:23.805996Z","end":"2026-07-27T20:55:23.997958Z","steps":["trace[1188299922] 'process raft request' (duration: 47.060461ms)","trace[1188299922] 'compare' (duration: 144.127571ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:23.998024Z","caller":"traceutil/trace.go:171","msg":"trace[1832148428] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"179.520088ms","start":"2026-07-27T20:55:23.818471Z","end":"2026-07-27T20:55:23.997991Z","steps":["trace[1832148428] 'process raft request' (duration: 179.317634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:23.998272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.307795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-27T20:55:23.998341Z","caller":"traceutil/trace.go:171","msg":"trace[1556263911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24705; }","duration":"177.426099ms","start":"2026-07-27T20:55:23.820899Z","end":"2026-07-27T20:55:23.998325Z","steps":["trace[1556263911] 'agreement among raft nodes before linearized reading' (duration: 177.187044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:23.998358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.672194ms","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-07-27T20:55:23.998437Z","caller":"traceutil/trace.go:171","msg":"trace[1126781063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24705; }","duration":"175.782247ms","start":"2026-07-27T20:55:23.822634Z","end":"2026-07-27T20:55:23.998416Z","steps":["trace[1126781063] 'agreement among raft nodes before linearized reading' (duration: 175.543552ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:24.169895Z","caller":"traceutil/trace.go:171","msg":"trace[774369522] transaction","detail":"{read_only:false; response_revision:24707; number_of_response:1; }","duration":"163.104995ms","start":"2026-07-27T20:55:24.006748Z","end":"2026-07-27T20:55:24.169853Z","steps":["trace[774369522] 'process raft request' (duration: 142.335007ms)","trace[774369522] 'compare' (duration: 20.617805ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:24.16992Z","caller":"traceutil/trace.go:171","msg":"trace[1932982576] linearizableReadLoop","detail":"{readStateIndex:25511; appliedIndex:25510; }","duration":"130.840566ms","start":"2026-07-27T20:55:24.039036Z","end":"2026-07-27T20:55:24.169877Z","steps":["trace[1932982576] 'read index received' (duration: 110.053338ms)","trace[1932982576] 'applied index is now lower than readState.Index' (duration: 20.786268ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:24.170197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.143552ms","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-07-27T20:55:24.170247Z","caller":"traceutil/trace.go:171","msg":"trace[304855355] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24708; }","duration":"131.241174ms","start":"2026-07-27T20:55:24.038991Z","end":"2026-07-27T20:55:24.170232Z","steps":["trace[304855355] 'agreement among raft nodes before linearized reading' (duration: 131.06634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:24.170302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.975ms","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-07-27T20:55:24.170428Z","caller":"traceutil/trace.go:171","msg":"trace[181169711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24708; }","duration":"128.104872ms","start":"2026-07-27T20:55:24.04228Z","end":"2026-07-27T20:55:24.170384Z","steps":["trace[181169711] 'agreement among raft nodes before linearized reading' (duration: 127.820677ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:24.375856Z","caller":"traceutil/trace.go:171","msg":"trace[341956344] transaction","detail":"{read_only:false; response_revision:24710; number_of_response:1; }","duration":"200.030792ms","start":"2026-07-27T20:55:24.175797Z","end":"2026-07-27T20:55:24.375828Z","steps":["trace[341956344] 'process raft request' (duration: 198.754077ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:24.624225Z","caller":"traceutil/trace.go:171","msg":"trace[1414070507] linearizableReadLoop","detail":"{readStateIndex:25516; appliedIndex:25515; }","duration":"147.482574ms","start":"2026-07-27T20:55:24.476717Z","end":"2026-07-27T20:55:24.6242Z","steps":["trace[1414070507] 'read index received' (duration: 147.24631ms)","trace[1414070507] 'applied index is now lower than readState.Index' (duration: 235.014µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:24.624343Z","caller":"traceutil/trace.go:171","msg":"trace[1103175486] transaction","detail":"{read_only:false; response_revision:24712; number_of_response:1; }","duration":"184.35325ms","start":"2026-07-27T20:55:24.439962Z","end":"2026-07-27T20:55:24.624315Z","steps":["trace[1103175486] 'process raft request' (duration: 184.068735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:24.624387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.649487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:24.624425Z","caller":"traceutil/trace.go:171","msg":"trace[1439310240] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24712; }","duration":"147.738749ms","start":"2026-07-27T20:55:24.476672Z","end":"2026-07-27T20:55:24.624411Z","steps":["trace[1439310240] 'agreement among raft nodes before linearized reading' (duration: 147.625227ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:24.717369Z","caller":"traceutil/trace.go:171","msg":"trace[1057693876] transaction","detail":"{read_only:false; response_revision:24713; number_of_response:1; }","duration":"138.663236ms","start":"2026-07-27T20:55:24.578659Z","end":"2026-07-27T20:55:24.717323Z","steps":["trace[1057693876] 'process raft request' (duration: 85.799383ms)","trace[1057693876] 'compare' (duration: 52.573717ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:25.017882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.038212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-27T20:55:25.017953Z","caller":"traceutil/trace.go:171","msg":"trace[1838488064] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24713; }","duration":"199.152825ms","start":"2026-07-27T20:55:24.818781Z","end":"2026-07-27T20:55:25.017934Z","steps":["trace[1838488064] 'range keys from in-memory index tree' (duration: 198.850279ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:25.150578Z","caller":"traceutil/trace.go:171","msg":"trace[948598189] linearizableReadLoop","detail":"{readStateIndex:25518; appliedIndex:25517; }","duration":"148.842671ms","start":"2026-07-27T20:55:25.001712Z","end":"2026-07-27T20:55:25.150554Z","steps":["trace[948598189] 'read index received' (duration: 148.609867ms)","trace[948598189] 'applied index is now lower than readState.Index' (duration: 231.904µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:25.150692Z","caller":"traceutil/trace.go:171","msg":"trace[173737795] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"173.796038ms","start":"2026-07-27T20:55:24.976883Z","end":"2026-07-27T20:55:25.150679Z","steps":["trace[173737795] 'process raft request' (duration: 173.520623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:25.150841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.492068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:25.150964Z","caller":"traceutil/trace.go:171","msg":"trace[792008004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24714; }","duration":"131.670122ms","start":"2026-07-27T20:55:25.019269Z","end":"2026-07-27T20:55:25.150939Z","steps":["trace[792008004] 'agreement among raft nodes before linearized reading' (duration: 131.456758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:25.151011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.275649ms","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-07-27T20:55:25.151103Z","caller":"traceutil/trace.go:171","msg":"trace[2127200592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24714; }","duration":"149.416193ms","start":"2026-07-27T20:55:25.001664Z","end":"2026-07-27T20:55:25.15108Z","steps":["trace[2127200592] 'agreement among raft nodes before linearized reading' (duration: 149.122686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:25.364169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.76379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:25.364264Z","caller":"traceutil/trace.go:171","msg":"trace[1661785275] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24715; }","duration":"136.904822ms","start":"2026-07-27T20:55:25.227341Z","end":"2026-07-27T20:55:25.364246Z","steps":["trace[1661785275] 'count revisions from in-memory index tree' (duration: 136.674848ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:25.493823Z","caller":"traceutil/trace.go:171","msg":"trace[2118747669] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"125.483853ms","start":"2026-07-27T20:55:25.3683Z","end":"2026-07-27T20:55:25.493784Z","steps":["trace[2118747669] 'process raft request' (duration: 125.32029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:27.767569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.832239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:55:27.767652Z","caller":"traceutil/trace.go:171","msg":"trace[633276881] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:24733; }","duration":"126.960131ms","start":"2026-07-27T20:55:27.640669Z","end":"2026-07-27T20:55:27.767629Z","steps":["trace[633276881] 'count revisions from in-memory index tree' (duration: 126.725847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:40.083807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.105216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:40.083914Z","caller":"traceutil/trace.go:171","msg":"trace[1044807866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24853; }","duration":"115.255448ms","start":"2026-07-27T20:55:39.968634Z","end":"2026-07-27T20:55:40.08389Z","steps":["trace[1044807866] 'range keys from in-memory index tree' (duration: 115.000413ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:40.213113Z","caller":"traceutil/trace.go:171","msg":"trace[1778110027] linearizableReadLoop","detail":"{readStateIndex:25661; appliedIndex:25660; }","duration":"128.227486ms","start":"2026-07-27T20:55:40.084852Z","end":"2026-07-27T20:55:40.213079Z","steps":["trace[1778110027] 'read index received' (duration: 127.991452ms)","trace[1778110027] 'applied index is now lower than readState.Index' (duration: 234.474µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:40.213174Z","caller":"traceutil/trace.go:171","msg":"trace[1718765323] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"131.780464ms","start":"2026-07-27T20:55:40.081368Z","end":"2026-07-27T20:55:40.213149Z","steps":["trace[1718765323] 'process raft request' (duration: 131.458378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:40.213311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.4237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:40.213436Z","caller":"traceutil/trace.go:171","msg":"trace[1675301218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24854; }","duration":"128.585613ms","start":"2026-07-27T20:55:40.084819Z","end":"2026-07-27T20:55:40.213405Z","steps":["trace[1675301218] 'agreement among raft nodes before linearized reading' (duration: 128.369119ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:40.384985Z","caller":"traceutil/trace.go:171","msg":"trace[1581999283] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"164.398489ms","start":"2026-07-27T20:55:40.220562Z","end":"2026-07-27T20:55:40.38496Z","steps":["trace[1581999283] 'process raft request' (duration: 164.349658ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:40.385114Z","caller":"traceutil/trace.go:171","msg":"trace[860936615] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"281.982571ms","start":"2026-07-27T20:55:40.10311Z","end":"2026-07-27T20:55:40.385093Z","steps":["trace[860936615] 'process raft request' (duration: 202.398876ms)","trace[860936615] 'compare' (duration: 79.258389ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:40.38524Z","caller":"traceutil/trace.go:171","msg":"trace[273378900] linearizableReadLoop","detail":"{readStateIndex:25662; appliedIndex:25661; }","duration":"171.993415ms","start":"2026-07-27T20:55:40.213198Z","end":"2026-07-27T20:55:40.385192Z","steps":["trace[273378900] 'read index received' (duration: 92.293198ms)","trace[273378900] 'applied index is now lower than readState.Index' (duration: 79.694106ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:40.385417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.60482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-27T20:55:40.385502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.043116ms","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-07-27T20:55:40.385535Z","caller":"traceutil/trace.go:171","msg":"trace[1427780388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24856; }","duration":"148.145168ms","start":"2026-07-27T20:55:40.237383Z","end":"2026-07-27T20:55:40.385528Z","steps":["trace[1427780388] 'agreement among raft nodes before linearized reading' (duration: 147.979755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:40.385416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.483762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-07-27T20:55:40.385558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.880004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-348e475a-349b-4a71-85cf-8e30e4532ee1\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-27T20:55:40.385593Z","caller":"traceutil/trace.go:171","msg":"trace[611822920] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24856; }","duration":"185.725417ms","start":"2026-07-27T20:55:40.199854Z","end":"2026-07-27T20:55:40.385579Z","steps":["trace[611822920] 'agreement among raft nodes before linearized reading' (duration: 185.36079ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:40.385611Z","caller":"traceutil/trace.go:171","msg":"trace[1526024622] range","detail":"{range_begin:/registry/persistentvolumes/pvc-348e475a-349b-4a71-85cf-8e30e4532ee1; range_end:; response_count:1; response_revision:24856; }","duration":"298.985706ms","start":"2026-07-27T20:55:40.086609Z","end":"2026-07-27T20:55:40.385595Z","steps":["trace[1526024622] 'agreement among raft nodes before linearized reading' (duration: 298.701361ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:40.385515Z","caller":"traceutil/trace.go:171","msg":"trace[1714821130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24856; }","duration":"274.780953ms","start":"2026-07-27T20:55:40.110713Z","end":"2026-07-27T20:55:40.385494Z","steps":["trace[1714821130] 'agreement among raft nodes before linearized reading' (duration: 274.509218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:40.385747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.972893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-27T20:55:40.385821Z","caller":"traceutil/trace.go:171","msg":"trace[1275293818] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24856; }","duration":"148.100696ms","start":"2026-07-27T20:55:40.237706Z","end":"2026-07-27T20:55:40.385806Z","steps":["trace[1275293818] 'agreement among raft nodes before linearized reading' (duration: 147.750159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:40.726721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.320207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:55:40.726827Z","caller":"traceutil/trace.go:171","msg":"trace[1462808264] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"273.57851ms","start":"2026-07-27T20:55:40.453228Z","end":"2026-07-27T20:55:40.726807Z","steps":["trace[1462808264] 'process raft request' (duration: 98.095579ms)","trace[1462808264] 'compare' (duration: 174.560743ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:40.764389Z","caller":"traceutil/trace.go:171","msg":"trace[791770325] linearizableReadLoop","detail":"{readStateIndex:25668; appliedIndex:25666; }","duration":"259.419639ms","start":"2026-07-27T20:55:40.504926Z","end":"2026-07-27T20:55:40.764346Z","steps":["trace[791770325] 'read index received' (duration: 46.406999ms)","trace[791770325] 'applied index is now lower than readState.Index' (duration: 213.01152ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:40.764461Z","caller":"traceutil/trace.go:171","msg":"trace[745174666] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"308.060381ms","start":"2026-07-27T20:55:40.456333Z","end":"2026-07-27T20:55:40.764393Z","steps":["trace[745174666] 'process raft request' (duration: 307.855137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:40.764588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:40.456315Z","time spent":"308.203083ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:55:40.764656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.630243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:40.764705Z","caller":"traceutil/trace.go:171","msg":"trace[1497364479] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24861; }","duration":"259.805166ms","start":"2026-07-27T20:55:40.504887Z","end":"2026-07-27T20:55:40.764693Z","steps":["trace[1497364479] 'agreement among raft nodes before linearized reading' (duration: 259.593702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:41.002899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.073873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:55:41.003063Z","caller":"traceutil/trace.go:171","msg":"trace[1122721911] transaction","detail":"{read_only:false; response_revision:24862; number_of_response:1; }","duration":"154.279155ms","start":"2026-07-27T20:55:40.848749Z","end":"2026-07-27T20:55:41.003028Z","steps":["trace[1122721911] 'process raft request' (duration: 13.965607ms)","trace[1122721911] 'compare' (duration: 139.847309ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:46.528945Z","caller":"traceutil/trace.go:171","msg":"trace[1707959988] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"125.452683ms","start":"2026-07-27T20:55:46.403465Z","end":"2026-07-27T20:55:46.528918Z","steps":["trace[1707959988] 'process raft request' (duration: 125.30094ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:46.772049Z","caller":"traceutil/trace.go:171","msg":"trace[1549140187] linearizableReadLoop","detail":"{readStateIndex:25706; appliedIndex:25704; }","duration":"299.973737ms","start":"2026-07-27T20:55:46.472053Z","end":"2026-07-27T20:55:46.772026Z","steps":["trace[1549140187] 'read index received' (duration: 56.790458ms)","trace[1549140187] 'applied index is now lower than readState.Index' (duration: 243.182319ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:46.772086Z","caller":"traceutil/trace.go:171","msg":"trace[448412885] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"365.661814ms","start":"2026-07-27T20:55:46.406403Z","end":"2026-07-27T20:55:46.772065Z","steps":["trace[448412885] 'process raft request' (duration: 365.47783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:46.772227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:46.406376Z","time spent":"365.762227ms","remote":"127.0.0.1:59348","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-07-27T20:55:46.772245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.19119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-27T20:55:46.772279Z","caller":"traceutil/trace.go:171","msg":"trace[3746740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24898; }","duration":"300.269172ms","start":"2026-07-27T20:55:46.472001Z","end":"2026-07-27T20:55:46.77227Z","steps":["trace[3746740] 'agreement among raft nodes before linearized reading' (duration: 300.13439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:46.772335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:46.471983Z","time spent":"300.344863ms","remote":"127.0.0.1:59348","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-07-27T20:55:46.785671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.730993ms","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-07-27T20:55:46.785751Z","caller":"traceutil/trace.go:171","msg":"trace[1978420811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24899; }","duration":"309.838064ms","start":"2026-07-27T20:55:46.475892Z","end":"2026-07-27T20:55:46.78573Z","steps":["trace[1978420811] 'agreement among raft nodes before linearized reading' (duration: 309.59973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:46.785794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:46.475881Z","time spent":"309.902075ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-27T20:55:46.786063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.803005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:46.786139Z","caller":"traceutil/trace.go:171","msg":"trace[483090418] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24899; }","duration":"190.889876ms","start":"2026-07-27T20:55:46.595231Z","end":"2026-07-27T20:55:46.78612Z","steps":["trace[483090418] 'agreement among raft nodes before linearized reading' (duration: 190.768684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:46.786242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.183412ms","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-07-27T20:55:46.786323Z","caller":"traceutil/trace.go:171","msg":"trace[1743698802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24899; }","duration":"310.317424ms","start":"2026-07-27T20:55:46.475981Z","end":"2026-07-27T20:55:46.786299Z","steps":["trace[1743698802] 'agreement among raft nodes before linearized reading' (duration: 310.050969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:46.786367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:46.47596Z","time spent":"310.398926ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-27T20:55:47.043051Z","caller":"traceutil/trace.go:171","msg":"trace[1643419315] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"253.052908ms","start":"2026-07-27T20:55:46.78997Z","end":"2026-07-27T20:55:47.043023Z","steps":["trace[1643419315] 'process raft request' (duration: 155.059811ms)","trace[1643419315] 'compare' (duration: 97.517068ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:47.043247Z","caller":"traceutil/trace.go:171","msg":"trace[906696261] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"252.74794ms","start":"2026-07-27T20:55:46.790466Z","end":"2026-07-27T20:55:47.043214Z","steps":["trace[906696261] 'process raft request' (duration: 252.475975ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:47.128159Z","caller":"traceutil/trace.go:171","msg":"trace[416012235] linearizableReadLoop","detail":"{readStateIndex:25711; appliedIndex:25708; }","duration":"293.285588ms","start":"2026-07-27T20:55:46.834851Z","end":"2026-07-27T20:55:47.128137Z","steps":["trace[416012235] 'read index received' (duration: 110.15592ms)","trace[416012235] 'applied index is now lower than readState.Index' (duration: 183.128828ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:47.130724Z","caller":"traceutil/trace.go:171","msg":"trace[484224273] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"324.419634ms","start":"2026-07-27T20:55:46.804044Z","end":"2026-07-27T20:55:47.128464Z","steps":["trace[484224273] 'process raft request' (duration: 323.973966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:47.130934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:46.804019Z","time spent":"326.78892ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:55:47.131135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.258065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-07-27T20:55:47.131216Z","caller":"traceutil/trace.go:171","msg":"trace[1936611823] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24903; }","duration":"296.337526ms","start":"2026-07-27T20:55:46.834846Z","end":"2026-07-27T20:55:47.131183Z","steps":["trace[1936611823] 'agreement among raft nodes before linearized reading' (duration: 293.42669ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:47.26334Z","caller":"traceutil/trace.go:171","msg":"trace[117736401] linearizableReadLoop","detail":"{readStateIndex:25712; appliedIndex:25711; }","duration":"132.640059ms","start":"2026-07-27T20:55:47.130673Z","end":"2026-07-27T20:55:47.263313Z","steps":["trace[117736401] 'read index received' (duration: 127.680995ms)","trace[117736401] 'applied index is now lower than readState.Index' (duration: 4.957904ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:47.263414Z","caller":"traceutil/trace.go:171","msg":"trace[327130138] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"242.989914ms","start":"2026-07-27T20:55:47.020384Z","end":"2026-07-27T20:55:47.263374Z","steps":["trace[327130138] 'process raft request' (duration: 237.995489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:47.263712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.896481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:47.263768Z","caller":"traceutil/trace.go:171","msg":"trace[1329675432] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:24904; }","duration":"256.050864ms","start":"2026-07-27T20:55:47.007699Z","end":"2026-07-27T20:55:47.26375Z","steps":["trace[1329675432] 'agreement among raft nodes before linearized reading' (duration: 255.768029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:47.263923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.555305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9e0a8494-4bd2-415a-84ab-9ff8b2870811\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-07-27T20:55:47.263962Z","caller":"traceutil/trace.go:171","msg":"trace[291005518] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9e0a8494-4bd2-415a-84ab-9ff8b2870811; range_end:; response_count:1; response_revision:24904; }","duration":"125.615786ms","start":"2026-07-27T20:55:47.13833Z","end":"2026-07-27T20:55:47.263946Z","steps":["trace[291005518] 'agreement among raft nodes before linearized reading' (duration: 125.464293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:47.263989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.710253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:47.264065Z","caller":"traceutil/trace.go:171","msg":"trace[320783153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24904; }","duration":"295.826446ms","start":"2026-07-27T20:55:46.968219Z","end":"2026-07-27T20:55:47.264046Z","steps":["trace[320783153] 'agreement among raft nodes before linearized reading' (duration: 295.703564ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:47.440423Z","caller":"traceutil/trace.go:171","msg":"trace[1072942355] linearizableReadLoop","detail":"{readStateIndex:25713; appliedIndex:25712; }","duration":"151.89672ms","start":"2026-07-27T20:55:47.288487Z","end":"2026-07-27T20:55:47.440383Z","steps":["trace[1072942355] 'read index received' (duration: 80.764878ms)","trace[1072942355] 'applied index is now lower than readState.Index' (duration: 71.130712ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:47.440496Z","caller":"traceutil/trace.go:171","msg":"trace[1109923676] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"159.814971ms","start":"2026-07-27T20:55:47.280641Z","end":"2026-07-27T20:55:47.440456Z","steps":["trace[1109923676] 'process raft request' (duration: 88.580406ms)","trace[1109923676] 'compare' (duration: 71.028041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:47.440697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.194676ms","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-07-27T20:55:47.440769Z","caller":"traceutil/trace.go:171","msg":"trace[1704783445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24905; }","duration":"152.319118ms","start":"2026-07-27T20:55:47.288433Z","end":"2026-07-27T20:55:47.440752Z","steps":["trace[1704783445] 'agreement among raft nodes before linearized reading' (duration: 152.081494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:49.456128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.300809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:55:49.456266Z","caller":"traceutil/trace.go:171","msg":"trace[1710156591] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"188.99519ms","start":"2026-07-27T20:55:49.267248Z","end":"2026-07-27T20:55:49.456243Z","steps":["trace[1710156591] 'process raft request' (duration: 37.472187ms)","trace[1710156591] 'compare' (duration: 151.148965ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:54.202184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.315886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:55:54.202272Z","caller":"traceutil/trace.go:171","msg":"trace[1021786821] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:24969; }","duration":"152.474341ms","start":"2026-07-27T20:55:54.04978Z","end":"2026-07-27T20:55:54.202255Z","steps":["trace[1021786821] 'count revisions from in-memory index tree' (duration: 152.213714ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:56.971068Z","caller":"traceutil/trace.go:171","msg":"trace[1866909940] linearizableReadLoop","detail":"{readStateIndex:25797; appliedIndex:25796; }","duration":"128.741956ms","start":"2026-07-27T20:55:56.842306Z","end":"2026-07-27T20:55:56.971048Z","steps":["trace[1866909940] 'read index received' (duration: 128.534312ms)","trace[1866909940] 'applied index is now lower than readState.Index' (duration: 206.464µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:56.971156Z","caller":"traceutil/trace.go:171","msg":"trace[1449287597] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"154.518749ms","start":"2026-07-27T20:55:56.816619Z","end":"2026-07-27T20:55:56.971137Z","steps":["trace[1449287597] 'process raft request' (duration: 154.236044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:56.971328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.001051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-27T20:55:56.971416Z","caller":"traceutil/trace.go:171","msg":"trace[824319278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24987; }","duration":"129.125153ms","start":"2026-07-27T20:55:56.842271Z","end":"2026-07-27T20:55:56.971396Z","steps":["trace[824319278] 'agreement among raft nodes before linearized reading' (duration: 128.889868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:59.318961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.510421ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:59.319082Z","caller":"traceutil/trace.go:171","msg":"trace[562200306] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25005; }","duration":"116.647304ms","start":"2026-07-27T20:55:59.202416Z","end":"2026-07-27T20:55:59.319064Z","steps":["trace[562200306] 'range keys from in-memory index tree' (duration: 116.497031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:59.319624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.165475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:55:59.319876Z","caller":"traceutil/trace.go:171","msg":"trace[1437295375] linearizableReadLoop","detail":"{readStateIndex:25818; appliedIndex:25816; }","duration":"136.176728ms","start":"2026-07-27T20:55:59.183677Z","end":"2026-07-27T20:55:59.319854Z","steps":["trace[1437295375] 'read index received' (duration: 19.28798ms)","trace[1437295375] 'applied index is now lower than readState.Index' (duration: 116.887468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:55:59.320031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.350872ms","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-07-27T20:55:59.320071Z","caller":"traceutil/trace.go:171","msg":"trace[1223788289] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25007; }","duration":"136.428904ms","start":"2026-07-27T20:55:59.183628Z","end":"2026-07-27T20:55:59.320057Z","steps":["trace[1223788289] 'agreement among raft nodes before linearized reading' (duration: 136.289321ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:59.320214Z","caller":"traceutil/trace.go:171","msg":"trace[315384202] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"226.899866ms","start":"2026-07-27T20:55:59.093263Z","end":"2026-07-27T20:55:59.320163Z","steps":["trace[315384202] 'process raft request' (duration: 109.68886ms)","trace[315384202] 'compare' (duration: 115.782129ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:59.320289Z","caller":"traceutil/trace.go:171","msg":"trace[99462694] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"224.946719ms","start":"2026-07-27T20:55:59.09517Z","end":"2026-07-27T20:55:59.320117Z","steps":["trace[99462694] 'process raft request' (duration: 224.573331ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:00.973244Z","caller":"traceutil/trace.go:171","msg":"trace[1965290566] linearizableReadLoop","detail":"{readStateIndex:25827; appliedIndex:25826; }","duration":"126.438071ms","start":"2026-07-27T20:56:00.846777Z","end":"2026-07-27T20:56:00.973215Z","steps":["trace[1965290566] 'read index received' (duration: 126.243548ms)","trace[1965290566] 'applied index is now lower than readState.Index' (duration: 193.693µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:00.973371Z","caller":"traceutil/trace.go:171","msg":"trace[671322093] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"144.62623ms","start":"2026-07-27T20:56:00.828716Z","end":"2026-07-27T20:56:00.973343Z","steps":["trace[671322093] 'process raft request' (duration: 144.348975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:00.973447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.642615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:00.97358Z","caller":"traceutil/trace.go:171","msg":"trace[920461534] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25016; }","duration":"126.795849ms","start":"2026-07-27T20:56:00.846729Z","end":"2026-07-27T20:56:00.973525Z","steps":["trace[920461534] 'agreement among raft nodes before linearized reading' (duration: 126.613715ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:01.212134Z","caller":"traceutil/trace.go:171","msg":"trace[1814766170] linearizableReadLoop","detail":"{readStateIndex:25828; appliedIndex:25827; }","duration":"155.813004ms","start":"2026-07-27T20:56:01.056298Z","end":"2026-07-27T20:56:01.212111Z","steps":["trace[1814766170] 'read index received' (duration: 63.6903ms)","trace[1814766170] 'applied index is now lower than readState.Index' (duration: 92.122014ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:01.212241Z","caller":"traceutil/trace.go:171","msg":"trace[2009247456] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"228.975926ms","start":"2026-07-27T20:56:00.983254Z","end":"2026-07-27T20:56:01.21223Z","steps":["trace[2009247456] 'process raft request' (duration: 136.80978ms)","trace[2009247456] 'compare' (duration: 91.578205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:01.212494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.418869ms","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-07-27T20:56:01.212604Z","caller":"traceutil/trace.go:171","msg":"trace[467950878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25017; }","duration":"141.53499ms","start":"2026-07-27T20:56:01.071014Z","end":"2026-07-27T20:56:01.212549Z","steps":["trace[467950878] 'agreement among raft nodes before linearized reading' (duration: 141.300676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:01.212494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.186771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-27T20:56:01.212682Z","caller":"traceutil/trace.go:171","msg":"trace[1090733912] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25017; }","duration":"156.391634ms","start":"2026-07-27T20:56:01.056262Z","end":"2026-07-27T20:56:01.212654Z","steps":["trace[1090733912] 'agreement among raft nodes before linearized reading' (duration: 156.090649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:01.48086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.569245ms","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-07-27T20:56:01.480929Z","caller":"traceutil/trace.go:171","msg":"trace[919685500] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25018; }","duration":"126.680707ms","start":"2026-07-27T20:56:01.354231Z","end":"2026-07-27T20:56:01.480912Z","steps":["trace[919685500] 'range keys from in-memory index tree' (duration: 126.420472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:01.480949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.016847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-27T20:56:01.481019Z","caller":"traceutil/trace.go:171","msg":"trace[1802929070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25018; }","duration":"157.128069ms","start":"2026-07-27T20:56:01.323875Z","end":"2026-07-27T20:56:01.481003Z","steps":["trace[1802929070] 'range keys from in-memory index tree' (duration: 156.853594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:01.481114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.795394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:01.481226Z","caller":"traceutil/trace.go:171","msg":"trace[1443908290] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25018; }","duration":"168.971256ms","start":"2026-07-27T20:56:01.31223Z","end":"2026-07-27T20:56:01.481201Z","steps":["trace[1443908290] 'range keys from in-memory index tree' (duration: 168.68733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:01.481278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.043907ms","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-07-27T20:56:01.481336Z","caller":"traceutil/trace.go:171","msg":"trace[1175111214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25018; }","duration":"158.135028ms","start":"2026-07-27T20:56:01.323182Z","end":"2026-07-27T20:56:01.481317Z","steps":["trace[1175111214] 'range keys from in-memory index tree' (duration: 157.908404ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:06.521723Z","caller":"traceutil/trace.go:171","msg":"trace[369900678] linearizableReadLoop","detail":"{readStateIndex:25870; appliedIndex:25869; }","duration":"137.87434ms","start":"2026-07-27T20:56:06.383829Z","end":"2026-07-27T20:56:06.521704Z","steps":["trace[369900678] 'read index received' (duration: 137.727897ms)","trace[369900678] 'applied index is now lower than readState.Index' (duration: 145.443µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:06.521918Z","caller":"traceutil/trace.go:171","msg":"trace[812276290] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"201.143441ms","start":"2026-07-27T20:56:06.320691Z","end":"2026-07-27T20:56:06.521835Z","steps":["trace[812276290] 'process raft request' (duration: 200.858196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:06.522047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.206106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-07-27T20:56:06.522079Z","caller":"traceutil/trace.go:171","msg":"trace[479372136] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25058; }","duration":"138.287968ms","start":"2026-07-27T20:56:06.383783Z","end":"2026-07-27T20:56:06.522071Z","steps":["trace[479372136] 'agreement among raft nodes before linearized reading' (duration: 138.071204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:06.52231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.093175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128467"} {"level":"info","ts":"2026-07-27T20:56:06.522387Z","caller":"traceutil/trace.go:171","msg":"trace[1965334593] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25058; }","duration":"127.215696ms","start":"2026-07-27T20:56:06.395153Z","end":"2026-07-27T20:56:06.522369Z","steps":["trace[1965334593] 'agreement among raft nodes before linearized reading' (duration: 126.770907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:06.727405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.548747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:06.727489Z","caller":"traceutil/trace.go:171","msg":"trace[27794551] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25058; }","duration":"146.69965ms","start":"2026-07-27T20:56:06.580771Z","end":"2026-07-27T20:56:06.72747Z","steps":["trace[27794551] 'range keys from in-memory index tree' (duration: 146.464716ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:06.727468Z","caller":"traceutil/trace.go:171","msg":"trace[1639093558] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"129.471279ms","start":"2026-07-27T20:56:06.597961Z","end":"2026-07-27T20:56:06.727433Z","steps":["trace[1639093558] 'process raft request' (duration: 99.803451ms)","trace[1639093558] 'compare' (duration: 29.495465ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:07.408045Z","caller":"traceutil/trace.go:171","msg":"trace[1643374524] linearizableReadLoop","detail":"{readStateIndex:25873; appliedIndex:25872; }","duration":"135.481925ms","start":"2026-07-27T20:56:07.272535Z","end":"2026-07-27T20:56:07.408017Z","steps":["trace[1643374524] 'read index received' (duration: 57.215926ms)","trace[1643374524] 'applied index is now lower than readState.Index' (duration: 78.264649ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:07.408124Z","caller":"traceutil/trace.go:171","msg":"trace[1923083476] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"175.495171ms","start":"2026-07-27T20:56:07.2326Z","end":"2026-07-27T20:56:07.408095Z","steps":["trace[1923083476] 'process raft request' (duration: 97.233962ms)","trace[1923083476] 'compare' (duration: 77.868002ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:07.408302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.756911ms","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-07-27T20:56:07.408339Z","caller":"traceutil/trace.go:171","msg":"trace[180990170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25061; }","duration":"135.837792ms","start":"2026-07-27T20:56:07.272491Z","end":"2026-07-27T20:56:07.408329Z","steps":["trace[180990170] 'agreement among raft nodes before linearized reading' (duration: 135.663068ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:07.655298Z","caller":"traceutil/trace.go:171","msg":"trace[1244509043] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"118.065512ms","start":"2026-07-27T20:56:07.537214Z","end":"2026-07-27T20:56:07.65528Z","steps":["trace[1244509043] 'process raft request' (duration: 118.037542ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:07.655376Z","caller":"traceutil/trace.go:171","msg":"trace[712388368] linearizableReadLoop","detail":"{readStateIndex:25875; appliedIndex:25874; }","duration":"155.257724ms","start":"2026-07-27T20:56:07.500096Z","end":"2026-07-27T20:56:07.655353Z","steps":["trace[712388368] 'read index received' (duration: 108.683793ms)","trace[712388368] 'applied index is now lower than readState.Index' (duration: 46.571931ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:07.655458Z","caller":"traceutil/trace.go:171","msg":"trace[1575585981] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"121.081129ms","start":"2026-07-27T20:56:07.534368Z","end":"2026-07-27T20:56:07.655449Z","steps":["trace[1575585981] 'process raft request' (duration: 120.851685ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:07.655435Z","caller":"traceutil/trace.go:171","msg":"trace[2063971162] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"187.945889ms","start":"2026-07-27T20:56:07.467391Z","end":"2026-07-27T20:56:07.655337Z","steps":["trace[2063971162] 'process raft request' (duration: 141.379787ms)","trace[2063971162] 'compare' (duration: 46.253226ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:07.655516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.408677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:07.655547Z","caller":"traceutil/trace.go:171","msg":"trace[1287942074] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25065; }","duration":"155.493008ms","start":"2026-07-27T20:56:07.500043Z","end":"2026-07-27T20:56:07.655536Z","steps":["trace[1287942074] 'agreement among raft nodes before linearized reading' (duration: 155.412736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:07.655607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.099205ms","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-07-27T20:56:07.655632Z","caller":"traceutil/trace.go:171","msg":"trace[1766163041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25065; }","duration":"127.176057ms","start":"2026-07-27T20:56:07.528449Z","end":"2026-07-27T20:56:07.655625Z","steps":["trace[1766163041] 'agreement among raft nodes before linearized reading' (duration: 127.076245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:07.655696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.393481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-27T20:56:07.655748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.056381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-27T20:56:07.655749Z","caller":"traceutil/trace.go:171","msg":"trace[981263349] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25065; }","duration":"102.496303ms","start":"2026-07-27T20:56:07.553243Z","end":"2026-07-27T20:56:07.655739Z","steps":["trace[981263349] 'agreement among raft nodes before linearized reading' (duration: 102.36943ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:07.655769Z","caller":"traceutil/trace.go:171","msg":"trace[2061350954] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25065; }","duration":"119.112441ms","start":"2026-07-27T20:56:07.536649Z","end":"2026-07-27T20:56:07.655761Z","steps":["trace[2061350954] 'agreement among raft nodes before linearized reading' (duration: 119.05881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:07.655865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.260914ms","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-07-27T20:56:07.65588Z","caller":"traceutil/trace.go:171","msg":"trace[1385786795] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25065; }","duration":"119.306965ms","start":"2026-07-27T20:56:07.536568Z","end":"2026-07-27T20:56:07.655875Z","steps":["trace[1385786795] 'agreement among raft nodes before linearized reading' (duration: 119.255934ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:07.908745Z","caller":"traceutil/trace.go:171","msg":"trace[1623766045] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"249.118361ms","start":"2026-07-27T20:56:07.659602Z","end":"2026-07-27T20:56:07.908721Z","steps":["trace[1623766045] 'process raft request' (duration: 222.447031ms)","trace[1623766045] 'compare' (duration: 26.332744ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:07.909006Z","caller":"traceutil/trace.go:171","msg":"trace[1356723206] linearizableReadLoop","detail":"{readStateIndex:25879; appliedIndex:25877; }","duration":"249.0501ms","start":"2026-07-27T20:56:07.659915Z","end":"2026-07-27T20:56:07.908965Z","steps":["trace[1356723206] 'read index received' (duration: 71.903736ms)","trace[1356723206] 'applied index is now lower than readState.Index' (duration: 177.145334ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:07.909071Z","caller":"traceutil/trace.go:171","msg":"trace[726802776] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"248.637812ms","start":"2026-07-27T20:56:07.660407Z","end":"2026-07-27T20:56:07.909045Z","steps":["trace[726802776] 'process raft request' (duration: 248.260435ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:07.909128Z","caller":"traceutil/trace.go:171","msg":"trace[1469742491] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"248.538309ms","start":"2026-07-27T20:56:07.660576Z","end":"2026-07-27T20:56:07.909114Z","steps":["trace[1469742491] 'process raft request' (duration: 248.270955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:07.909235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.300004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:56:07.909276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.375323ms","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-07-27T20:56:07.909291Z","caller":"traceutil/trace.go:171","msg":"trace[821481575] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25069; }","duration":"249.391366ms","start":"2026-07-27T20:56:07.659886Z","end":"2026-07-27T20:56:07.909277Z","steps":["trace[821481575] 'agreement among raft nodes before linearized reading' (duration: 249.277594ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:07.909315Z","caller":"traceutil/trace.go:171","msg":"trace[1167150858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25069; }","duration":"217.459385ms","start":"2026-07-27T20:56:07.691844Z","end":"2026-07-27T20:56:07.909304Z","steps":["trace[1167150858] 'agreement among raft nodes before linearized reading' (duration: 217.323582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:11.947954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.858054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-27T20:56:11.948008Z","caller":"traceutil/trace.go:171","msg":"trace[597178874] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25094; }","duration":"191.944527ms","start":"2026-07-27T20:56:11.756052Z","end":"2026-07-27T20:56:11.947996Z","steps":["trace[597178874] 'range keys from in-memory index tree' (duration: 191.740902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:12.298753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.370817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:12.29901Z","caller":"traceutil/trace.go:171","msg":"trace[1969619584] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"143.61513ms","start":"2026-07-27T20:56:12.15538Z","end":"2026-07-27T20:56:12.298995Z","steps":["trace[1969619584] 'process raft request' (duration: 143.578659ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:12.299157Z","caller":"traceutil/trace.go:171","msg":"trace[1965969637] linearizableReadLoop","detail":"{readStateIndex:25910; appliedIndex:25907; }","duration":"332.825464ms","start":"2026-07-27T20:56:11.96631Z","end":"2026-07-27T20:56:12.299136Z","steps":["trace[1965969637] 'read index received' (duration: 58.195614ms)","trace[1965969637] 'applied index is now lower than readState.Index' (duration: 274.62793ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:12.299163Z","caller":"traceutil/trace.go:171","msg":"trace[262917547] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"347.685859ms","start":"2026-07-27T20:56:11.951456Z","end":"2026-07-27T20:56:12.299142Z","steps":["trace[262917547] 'process raft request' (duration: 347.377604ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:12.299204Z","caller":"traceutil/trace.go:171","msg":"trace[661824923] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"305.125314ms","start":"2026-07-27T20:56:11.994046Z","end":"2026-07-27T20:56:12.299171Z","steps":["trace[661824923] 'process raft request' (duration: 304.879128ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:12.299218Z","caller":"traceutil/trace.go:171","msg":"trace[832391582] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"347.929714ms","start":"2026-07-27T20:56:11.951255Z","end":"2026-07-27T20:56:12.299185Z","steps":["trace[832391582] 'process raft request' (duration: 158.055307ms)","trace[832391582] 'compare' (duration: 189.00636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:12.299273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.970988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:56:12.299339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:11.994028Z","time spent":"305.239097ms","remote":"127.0.0.1:59348","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-07-27T20:56:12.299344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:11.951232Z","time spent":"348.041797ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:12.299351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.697702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-07-27T20:56:12.299387Z","caller":"traceutil/trace.go:171","msg":"trace[1800042836] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25099; }","duration":"261.770794ms","start":"2026-07-27T20:56:12.037607Z","end":"2026-07-27T20:56:12.299378Z","steps":["trace[1800042836] 'agreement among raft nodes before linearized reading' (duration: 261.649791ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:12.299356Z","caller":"traceutil/trace.go:171","msg":"trace[380205418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25099; }","duration":"333.09128ms","start":"2026-07-27T20:56:11.966252Z","end":"2026-07-27T20:56:12.299344Z","steps":["trace[380205418] 'agreement among raft nodes before linearized reading' (duration: 332.960488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:12.29944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:11.966231Z","time spent":"333.203112ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T20:56:12.299413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.859141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-27T20:56:12.299292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:11.95143Z","time spent":"347.786372ms","remote":"127.0.0.1:59348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:12.299491Z","caller":"traceutil/trace.go:171","msg":"trace[1388869876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25099; }","duration":"290.968613ms","start":"2026-07-27T20:56:12.008497Z","end":"2026-07-27T20:56:12.299465Z","steps":["trace[1388869876] 'agreement among raft nodes before linearized reading' (duration: 290.759319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:12.299288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.578938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:12.299548Z","caller":"traceutil/trace.go:171","msg":"trace[1596127681] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25099; }","duration":"205.879224ms","start":"2026-07-27T20:56:12.093657Z","end":"2026-07-27T20:56:12.299537Z","steps":["trace[1596127681] 'agreement among raft nodes before linearized reading' (duration: 205.594408ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:41.553795Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000664f.wal"} {"level":"info","ts":"2026-07-27T20:56:45.063939Z","caller":"traceutil/trace.go:171","msg":"trace[1554710093] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"161.023684ms","start":"2026-07-27T20:56:44.902886Z","end":"2026-07-27T20:56:45.06391Z","steps":["trace[1554710093] 'process raft request' (duration: 160.82612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.725858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.505304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:56:45.725948Z","caller":"traceutil/trace.go:171","msg":"trace[1051964910] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25493; }","duration":"290.655237ms","start":"2026-07-27T20:56:45.435274Z","end":"2026-07-27T20:56:45.725929Z","steps":["trace[1051964910] 'count revisions from in-memory index tree' (duration: 290.391122ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:45.815943Z","caller":"traceutil/trace.go:171","msg":"trace[1534225370] linearizableReadLoop","detail":"{readStateIndex:26316; appliedIndex:26315; }","duration":"111.881773ms","start":"2026-07-27T20:56:45.704044Z","end":"2026-07-27T20:56:45.815925Z","steps":["trace[1534225370] 'read index received' (duration: 111.796022ms)","trace[1534225370] 'applied index is now lower than readState.Index' (duration: 84.831µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:45.816015Z","caller":"traceutil/trace.go:171","msg":"trace[1366106900] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"196.297139ms","start":"2026-07-27T20:56:45.619671Z","end":"2026-07-27T20:56:45.815968Z","steps":["trace[1366106900] 'process raft request' (duration: 196.121826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.816134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.073727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-27T20:56:45.816162Z","caller":"traceutil/trace.go:171","msg":"trace[577022978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25494; }","duration":"112.151658ms","start":"2026-07-27T20:56:45.704003Z","end":"2026-07-27T20:56:45.816154Z","steps":["trace[577022978] 'agreement among raft nodes before linearized reading' (duration: 112.038006ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:46.008711Z","caller":"traceutil/trace.go:171","msg":"trace[1686131359] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"187.94651ms","start":"2026-07-27T20:56:45.82074Z","end":"2026-07-27T20:56:46.008686Z","steps":["trace[1686131359] 'process raft request' (duration: 187.737126ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:46.027292Z","caller":"traceutil/trace.go:171","msg":"trace[483029001] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"196.653947ms","start":"2026-07-27T20:56:45.830615Z","end":"2026-07-27T20:56:46.027269Z","steps":["trace[483029001] 'process raft request' (duration: 196.481294ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:46.027465Z","caller":"traceutil/trace.go:171","msg":"trace[697952805] linearizableReadLoop","detail":"{readStateIndex:26318; appliedIndex:26317; }","duration":"128.756836ms","start":"2026-07-27T20:56:45.898675Z","end":"2026-07-27T20:56:46.027432Z","steps":["trace[697952805] 'read index received' (duration: 110.811462ms)","trace[697952805] 'applied index is now lower than readState.Index' (duration: 17.943174ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:46.027505Z","caller":"traceutil/trace.go:171","msg":"trace[1410344584] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"112.935203ms","start":"2026-07-27T20:56:45.914547Z","end":"2026-07-27T20:56:46.027483Z","steps":["trace[1410344584] 'process raft request' (duration: 112.682879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:46.027611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.913629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:56:46.027694Z","caller":"traceutil/trace.go:171","msg":"trace[1609150350] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25497; }","duration":"129.041582ms","start":"2026-07-27T20:56:45.898632Z","end":"2026-07-27T20:56:46.027673Z","steps":["trace[1609150350] 'agreement among raft nodes before linearized reading' (duration: 128.913339ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:58:04.953831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23314} {"level":"info","ts":"2026-07-27T20:58:05.085599Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23314,"took":"127.88632ms","hash":1496232927,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":36724736,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-27T20:58:05.085667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1496232927,"revision":23314,"compact-revision":20099} {"level":"info","ts":"2026-07-27T21:03:04.959216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26203} {"level":"info","ts":"2026-07-27T21:03:05.099549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26203,"took":"136.464363ms","hash":884875605,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":33951744,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-27T21:03:05.099618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":884875605,"revision":26203,"compact-revision":23314} {"level":"info","ts":"2026-07-27T21:03:34.520405Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T21:03:34.525804Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-27T21:03:34.52596Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-27T21:08:04.970286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28869} {"level":"info","ts":"2026-07-27T21:08:05.108586Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28869,"took":"133.803482ms","hash":333390919,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":30588928,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-27T21:08:05.108656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":333390919,"revision":28869,"compact-revision":26203} {"level":"info","ts":"2026-07-27T21:13:04.978175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31121} {"level":"info","ts":"2026-07-27T21:13:05.100431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31121,"took":"118.196291ms","hash":1241901881,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":30560256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-27T21:13:05.100509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1241901881,"revision":31121,"compact-revision":28869} {"level":"info","ts":"2026-07-27T21:18:04.986033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33385} {"level":"info","ts":"2026-07-27T21:18:05.143426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33385,"took":"153.399605ms","hash":2582440556,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":32350208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T21:18:05.143492Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2582440556,"revision":33385,"compact-revision":31121} {"level":"info","ts":"2026-07-27T21:23:04.991712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35683} {"level":"info","ts":"2026-07-27T21:23:05.135002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35683,"took":"138.726194ms","hash":2694746690,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":32145408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T21:23:05.13507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2694746690,"revision":35683,"compact-revision":33385} {"level":"info","ts":"2026-07-27T21:25:00.109513Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T21:25:00.114505Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-27T21:25:00.114601Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-27T21:28:04.999761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37908} {"level":"info","ts":"2026-07-27T21:28:05.133814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37908,"took":"130.213511ms","hash":1335159740,"current-db-size-bytes":58957824,"current-db-size":"59 MB","current-db-size-in-use-bytes":31191040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-27T21:28:05.133882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1335159740,"revision":37908,"compact-revision":35683}